builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0045 starttime: 1467907439.51 results: success (0) buildid: 20160707070123 builduid: 1830e053c27a4a6282bd3761f34a892c revision: ba5ff277a3cd1a7f655f8e02a130a395e8f2a225 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 09:03:59.509059) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 09:03:59.509540) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 09:03:59.509842) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 09:03:59.532974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 09:03:59.533239) ========= 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.mCMUmqEzjd/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.wyrXBhpMMm/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-07 09:03:59-- 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% 14.1M=0.001s 2016-07-07 09:03:59 (14.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.135919 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 09:03:59.691446) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 09:03:59.691746) ========= 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.mCMUmqEzjd/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.wyrXBhpMMm/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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.020234 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 09:03:59.741566) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 09:03:59.741927) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ba5ff277a3cd1a7f655f8e02a130a395e8f2a225 --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 ba5ff277a3cd1a7f655f8e02a130a395e8f2a225 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mCMUmqEzjd/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.wyrXBhpMMm/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-07 09:03:59,816 truncating revision to first 12 chars 2016-07-07 09:03:59,816 Setting DEBUG logging. 2016-07-07 09:03:59,816 attempt 1/10 2016-07-07 09:03:59,816 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ba5ff277a3cd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-07 09:04:01,011 unpacking tar archive at: fx-team-ba5ff277a3cd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.632863 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 09:04:01.396944) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 09:04:01.397266) ========= script_repo_revision: ba5ff277a3cd1a7f655f8e02a130a395e8f2a225 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 09:04:01.397649) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 09:04:01.397926) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 09:04:01.415749) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-07-07 09:04:01.416146) ========= /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.mCMUmqEzjd/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.wyrXBhpMMm/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:04:01 INFO - MultiFileLogger online at 20160707 09:04:01 in /builds/slave/test 09:04:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:04:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:04:01 INFO - {'append_to_log': False, 09:04:01 INFO - 'base_work_dir': '/builds/slave/test', 09:04:01 INFO - 'blob_upload_branch': 'Fx-Team', 09:04:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:04:01 INFO - 'branch': 'Fx-Team', 09:04:01 INFO - 'buildbot_json_path': 'buildprops.json', 09:04:01 INFO - 'config_files': ('talos/mac_config.py',), 09:04:01 INFO - 'default_actions': ('clobber', 09:04:01 INFO - 'read-buildbot-config', 09:04:01 INFO - 'download-and-extract', 09:04:01 INFO - 'populate-webroot', 09:04:01 INFO - 'create-virtualenv', 09:04:01 INFO - 'install', 09:04:01 INFO - 'run-tests'), 09:04:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:04:01 INFO - 'download_minidump_stackwalk': True, 09:04:01 INFO - 'download_symbols': 'ondemand', 09:04:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:04:01 INFO - 'tooltool.py': '/tools/tooltool.py', 09:04:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:04:01 INFO - '/tools/misc-python/virtualenv.py')}, 09:04:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:04:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:04:01 INFO - 'installer_path': 'installer.exe', 09:04:01 INFO - 'log_level': 'info', 09:04:01 INFO - 'log_name': 'talos', 09:04:01 INFO - 'log_to_console': True, 09:04:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:04:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:04:01 INFO - 'opt_config_files': (), 09:04:01 INFO - 'pip_index': False, 09:04:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:04:01 INFO - 'cmd': ('bash', 09:04:01 INFO - '-c', 09:04:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:04:01 INFO - 'enabled': True, 09:04:01 INFO - 'halt_on_failure': False, 09:04:01 INFO - 'name': 'check_screen_resolution'},), 09:04:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:04:01 INFO - 'cmd': ('bash', 09:04:01 INFO - '-c', 09:04:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:04:01 INFO - 'enabled': True, 09:04:01 INFO - 'halt_on_failure': False, 09:04:01 INFO - 'name': 'check_screen_resolution'},), 09:04:01 INFO - 'run_cmd_checks_enabled': True, 09:04:01 INFO - 'sps_profile': False, 09:04:01 INFO - 'sps_profile_interval': 0, 09:04:01 INFO - 'suite': 'dromaeojs', 09:04:01 INFO - 'system_bits': '32', 09:04:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:04:01 INFO - 'title': 't-yosemite-r7-0045', 09:04:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:04:01 INFO - 'use_talos_json': True, 09:04:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:04:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:04:01 INFO - 'work_dir': 'build'} 09:04:01 INFO - ##### 09:04:01 INFO - ##### Running clobber step. 09:04:01 INFO - ##### 09:04:01 INFO - Running pre-action listener: _resource_record_pre_action 09:04:01 INFO - Running main action method: clobber 09:04:01 INFO - rmtree: /builds/slave/test/build 09:04:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:04:03 INFO - Running post-action listener: _resource_record_post_action 09:04:03 INFO - ##### 09:04:03 INFO - ##### Finished clobber step (success) 09:04:03 INFO - ##### 09:04:03 INFO - ##### 09:04:03 INFO - ##### Running read-buildbot-config step. 09:04:03 INFO - ##### 09:04:03 INFO - Running pre-action listener: _resource_record_pre_action 09:04:03 INFO - Running main action method: read_buildbot_config 09:04:03 INFO - Using buildbot properties: 09:04:03 INFO - { 09:04:03 INFO - "project": "", 09:04:03 INFO - "product": "firefox", 09:04:03 INFO - "script_repo_revision": "production", 09:04:03 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:04:03 INFO - "repository": "", 09:04:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:04:03 INFO - "buildid": "20160707070123", 09:04:03 INFO - "pgo_build": "False", 09:04:03 INFO - "basedir": "/builds/slave/test", 09:04:03 INFO - "buildnumber": 72, 09:04:03 INFO - "slavename": "t-yosemite-r7-0045", 09:04:03 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:04:03 INFO - "platform": "yosemite_r7", 09:04:03 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:04:03 INFO - "branch": "fx-team", 09:04:03 INFO - "repo_path": "integration/fx-team", 09:04:03 INFO - "revision": "ba5ff277a3cd1a7f655f8e02a130a395e8f2a225", 09:04:03 INFO - "stage_platform": "macosx64", 09:04:03 INFO - "builduid": "1830e053c27a4a6282bd3761f34a892c", 09:04:03 INFO - "slavebuilddir": "test" 09:04:03 INFO - } 09:04:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:04:03 INFO - Running post-action listener: _resource_record_post_action 09:04:03 INFO - ##### 09:04:03 INFO - ##### Finished read-buildbot-config step (success) 09:04:03 INFO - ##### 09:04:03 INFO - ##### 09:04:03 INFO - ##### Running download-and-extract step. 09:04:03 INFO - ##### 09:04:03 INFO - Running pre-action listener: _resource_record_pre_action 09:04:03 INFO - Running main action method: download_and_extract 09:04:03 INFO - mkdir: /builds/slave/test/build/tests 09:04:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:03 INFO - https://queue.taskcluster.net/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:04:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:04:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:04:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:04:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 09:04:04 INFO - Downloaded 1199 bytes. 09:04:04 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:04:04 INFO - Using the following test package requirements: 09:04:04 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:04:04 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:04:04 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:04:04 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:04:04 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:04:04 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:04:04 INFO - u'jsshell-mac.zip'], 09:04:04 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:04:04 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:04:04 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:04:04 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:04:04 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:04:04 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:04:04 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:04:04 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:04:04 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:04:04 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:04:04 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:04:04 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:04:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:04 INFO - https://queue.taskcluster.net/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:04:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:04:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:04:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:04:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:04:06 INFO - Downloaded 18806161 bytes. 09:04:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:04:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:04:09 INFO - Return code: 0 09:04:09 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:04:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:09 INFO - https://queue.taskcluster.net/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:04:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:04:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:04:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:04:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:04:12 INFO - Downloaded 18806161 bytes. 09:04:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:04:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:04:16 INFO - Return code: 0 09:04: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')]} 09:04:16 INFO - https://queue.taskcluster.net/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:04:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:04:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:04:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:04:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:04:17 INFO - Downloaded 14403333 bytes. 09:04:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:04:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:04:18 INFO - Return code: 0 09:04:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:18 INFO - https://queue.taskcluster.net/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:04:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:04:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:04:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:04:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 09:04:25 INFO - Downloaded 112423571 bytes. 09:04:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:04:25 INFO - mkdir: /builds/slave/test/properties 09:04:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:04:25 INFO - Writing to file /builds/slave/test/properties/build_url 09:04:25 INFO - Contents: 09:04:25 INFO - build_url:https://queue.taskcluster.net/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:04:26 INFO - Running post-action listener: _resource_record_post_action 09:04:26 INFO - Running post-action listener: set_extra_try_arguments 09:04:26 INFO - ##### 09:04:26 INFO - ##### Finished download-and-extract step (success) 09:04:26 INFO - ##### 09:04:26 INFO - ##### 09:04:26 INFO - ##### Running populate-webroot step. 09:04:26 INFO - ##### 09:04:26 INFO - Running pre-action listener: _resource_record_pre_action 09:04:26 INFO - Running main action method: populate_webroot 09:04:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:04:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:04:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:04:26 INFO - '--tppagecycles', 09:04:26 INFO - '7'], 09:04:26 INFO - 'tests': ['tsvgm']}}, 09:04:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:04:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:04:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:04:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:04:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:04:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:04:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:04:26 INFO - 'talos_options': ['--disable-e10s'], 09:04:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:04:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:04:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:04:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:04:26 INFO - 'talos_options': ['--disable-e10s'], 09:04:26 INFO - 'tests': ['damp', 'tps']}, 09:04:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:04:26 INFO - 'tests': ['damp', 'tps']}, 09:04:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:04:26 INFO - 'tests': ['dromaeo_dom']}, 09:04:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:04:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:04:26 INFO - 'tests': ['basic_compositor_video']}, 09:04:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:04:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:04:26 INFO - 'tests': ['a11yr', 09:04:26 INFO - 'ts_paint', 09:04:26 INFO - 'tpaint', 09:04:26 INFO - 'sessionrestore', 09:04:26 INFO - 'sessionrestore_no_auto_restore', 09:04:26 INFO - 'tabpaint']}, 09:04:26 INFO - 'other-e10s': {'tests': ['a11yr', 09:04:26 INFO - 'ts_paint', 09:04:26 INFO - 'tpaint', 09:04:26 INFO - 'sessionrestore', 09:04:26 INFO - 'sessionrestore_no_auto_restore', 09:04:26 INFO - 'tabpaint']}, 09:04:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:04:26 INFO - 'ts_paint', 09:04:26 INFO - 'tpaint', 09:04:26 INFO - 'sessionrestore', 09:04:26 INFO - 'sessionrestore_no_auto_restore', 09:04:26 INFO - 'tabpaint']}, 09:04:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:04:26 INFO - 'ts_paint', 09:04:26 INFO - 'tpaint', 09:04:26 INFO - 'sessionrestore', 09:04:26 INFO - 'sessionrestore_no_auto_restore', 09:04:26 INFO - 'tabpaint']}, 09:04:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:04:26 INFO - 'tests': ['a11yr', 09:04:26 INFO - 'ts_paint', 09:04:26 INFO - 'tpaint', 09:04:26 INFO - 'sessionrestore', 09:04:26 INFO - 'sessionrestore_no_auto_restore', 09:04:26 INFO - 'tabpaint']}, 09:04:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:04:26 INFO - 'tests': ['a11yr', 09:04:26 INFO - 'ts_paint', 09:04:26 INFO - 'tpaint', 09:04:26 INFO - 'sessionrestore', 09:04:26 INFO - 'sessionrestore_no_auto_restore', 09:04:26 INFO - 'tabpaint']}, 09:04:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:04:26 INFO - 'tests': ['tsvgx', 09:04:26 INFO - 'tsvgr_opacity', 09:04:26 INFO - 'tart', 09:04:26 INFO - 'tscrollx', 09:04:26 INFO - 'cart']}, 09:04:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:04:26 INFO - 'tsvgr_opacity', 09:04:26 INFO - 'tart', 09:04:26 INFO - 'tscrollx', 09:04:26 INFO - 'cart']}, 09:04:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:04:26 INFO - 'talos_options': ['--disable-e10s'], 09:04:26 INFO - 'tests': ['tp5o']}, 09:04:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:04:26 INFO - 'tests': ['tp5o']}, 09:04:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:04:26 INFO - 'talos_options': ['--disable-e10s', 09:04:26 INFO - '--xperf_path', 09:04:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:04:26 INFO - 'tests': ['tp5n']}, 09:04:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:04:26 INFO - 'talos_options': ['--xperf_path', 09:04:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:04:26 INFO - 'tests': ['tp5n']}}, 09:04:26 INFO - 'talos.zip': {'path': '', 09:04:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:04:26 INFO - Running post-action listener: _resource_record_post_action 09:04:26 INFO - ##### 09:04:26 INFO - ##### Finished populate-webroot step (success) 09:04:26 INFO - ##### 09:04:26 INFO - ##### 09:04:26 INFO - ##### Running create-virtualenv step. 09:04:26 INFO - ##### 09:04:26 INFO - Running pre-action listener: _resource_record_pre_action 09:04:26 INFO - Running main action method: create_virtualenv 09:04:26 INFO - Creating virtualenv /builds/slave/test/build/venv 09:04:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:04:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:04:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:04:26 INFO - Using real prefix '/tools/python27' 09:04:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:04:27 INFO - Installing distribute.............................................................................................................................................................................................done. 09:04:30 INFO - Installing pip.................done. 09:04:30 INFO - Return code: 0 09:04:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:04:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:30 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 0x106135a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8d3cacca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, '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 0x105dfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106091b20>, '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.mCMUmqEzjd/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.wyrXBhpMMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:04:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:04:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:04:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mCMUmqEzjd/Render', 09:04:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:30 INFO - 'HOME': '/Users/cltbld', 09:04:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:30 INFO - 'LOGNAME': 'cltbld', 09:04:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:30 INFO - 'MOZ_NO_REMOTE': '1', 09:04:30 INFO - 'NO_EM_RESTART': '1', 09:04:30 INFO - 'PAGER': '/bin/cat', 09:04:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:30 INFO - 'PWD': '/builds/slave/test', 09:04:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:30 INFO - 'SHELL': '/bin/bash', 09:04:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wyrXBhpMMm/Listeners', 09:04:30 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 09:04:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:30 INFO - 'USER': 'cltbld', 09:04:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:30 INFO - 'XPC_FLAGS': '0x0', 09:04:30 INFO - 'XPC_SERVICE_NAME': '0', 09:04:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:04:30 INFO - Cleaning up... 09:04:30 INFO - Return code: 0 09:04:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:04:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:30 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 0x106135a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8d3cacca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, '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 0x105dfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106091b20>, '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.mCMUmqEzjd/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.wyrXBhpMMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:04:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:04:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:04:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mCMUmqEzjd/Render', 09:04:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:30 INFO - 'HOME': '/Users/cltbld', 09:04:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:30 INFO - 'LOGNAME': 'cltbld', 09:04:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:30 INFO - 'MOZ_NO_REMOTE': '1', 09:04:30 INFO - 'NO_EM_RESTART': '1', 09:04:30 INFO - 'PAGER': '/bin/cat', 09:04:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:30 INFO - 'PWD': '/builds/slave/test', 09:04:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:30 INFO - 'SHELL': '/bin/bash', 09:04:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wyrXBhpMMm/Listeners', 09:04:30 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 09:04:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:30 INFO - 'USER': 'cltbld', 09:04:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:30 INFO - 'XPC_FLAGS': '0x0', 09:04:30 INFO - 'XPC_SERVICE_NAME': '0', 09:04:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:30 INFO - Downloading/unpacking psutil>=3.1.1 09:04:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:04:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:04:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:04:34 INFO - Installing collected packages: psutil 09:04:34 INFO - Running setup.py install for psutil 09:04:34 INFO - building 'psutil._psutil_osx' extension 09:04:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:04:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:04:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:04:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:04:34 INFO - building 'psutil._psutil_posix' extension 09:04:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:04:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:04:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:04:34 INFO - ^ 09:04:34 INFO - 1 warning generated. 09:04:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:04:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:04:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:04:35 INFO - Successfully installed psutil 09:04:35 INFO - Cleaning up... 09:04:35 INFO - Return code: 0 09:04:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:04:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106135a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8d3cacca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, '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 0x105dfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106091b20>, '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.mCMUmqEzjd/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.wyrXBhpMMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:04:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:04:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:04:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mCMUmqEzjd/Render', 09:04:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:35 INFO - 'HOME': '/Users/cltbld', 09:04:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:35 INFO - 'LOGNAME': 'cltbld', 09:04:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:35 INFO - 'MOZ_NO_REMOTE': '1', 09:04:35 INFO - 'NO_EM_RESTART': '1', 09:04:35 INFO - 'PAGER': '/bin/cat', 09:04:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:35 INFO - 'PWD': '/builds/slave/test', 09:04:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:35 INFO - 'SHELL': '/bin/bash', 09:04:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wyrXBhpMMm/Listeners', 09:04:35 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 09:04:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:35 INFO - 'USER': 'cltbld', 09:04:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:35 INFO - 'XPC_FLAGS': '0x0', 09:04:35 INFO - 'XPC_SERVICE_NAME': '0', 09:04:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:04:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:04:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:04:37 INFO - Installing collected packages: mozsystemmonitor 09:04:37 INFO - Running setup.py install for mozsystemmonitor 09:04:38 INFO - Successfully installed mozsystemmonitor 09:04:38 INFO - Cleaning up... 09:04:38 INFO - Return code: 0 09:04:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:38 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 0x106135a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8d3cacca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, '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 0x105dfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106091b20>, '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.mCMUmqEzjd/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.wyrXBhpMMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:04:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:04:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:04:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mCMUmqEzjd/Render', 09:04:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:38 INFO - 'HOME': '/Users/cltbld', 09:04:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:38 INFO - 'LOGNAME': 'cltbld', 09:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:38 INFO - 'MOZ_NO_REMOTE': '1', 09:04:38 INFO - 'NO_EM_RESTART': '1', 09:04:38 INFO - 'PAGER': '/bin/cat', 09:04:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:38 INFO - 'PWD': '/builds/slave/test', 09:04:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:38 INFO - 'SHELL': '/bin/bash', 09:04:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wyrXBhpMMm/Listeners', 09:04:38 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 09:04:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:38 INFO - 'USER': 'cltbld', 09:04:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:38 INFO - 'XPC_FLAGS': '0x0', 09:04:38 INFO - 'XPC_SERVICE_NAME': '0', 09:04:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:38 INFO - Downloading/unpacking jsonschema==2.5.1 09:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:04:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:41 INFO - Downloading functools32-3.2.3-2.tar.gz 09:04:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:04:41 INFO - warning: no files found matching '*.txt' 09:04:41 INFO - no previously-included directories found matching 'build' 09:04:41 INFO - no previously-included directories found matching 'dist' 09:04:41 INFO - no previously-included directories found matching '.git*' 09:04:41 INFO - Installing collected packages: jsonschema, functools32 09:04:41 INFO - Running setup.py install for functools32 09:04:41 INFO - warning: no files found matching '*.txt' 09:04:41 INFO - no previously-included directories found matching 'build' 09:04:41 INFO - no previously-included directories found matching 'dist' 09:04:41 INFO - no previously-included directories found matching '.git*' 09:04:41 INFO - Successfully installed jsonschema functools32 09:04:41 INFO - Cleaning up... 09:04:41 INFO - Return code: 0 09:04:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:41 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 0x106135a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8d3cacca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, '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 0x105dfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106091b20>, '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.mCMUmqEzjd/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.wyrXBhpMMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:04:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:04:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:04:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mCMUmqEzjd/Render', 09:04:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:41 INFO - 'HOME': '/Users/cltbld', 09:04:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:41 INFO - 'LOGNAME': 'cltbld', 09:04:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:41 INFO - 'MOZ_NO_REMOTE': '1', 09:04:41 INFO - 'NO_EM_RESTART': '1', 09:04:41 INFO - 'PAGER': '/bin/cat', 09:04:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:41 INFO - 'PWD': '/builds/slave/test', 09:04:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:41 INFO - 'SHELL': '/bin/bash', 09:04:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wyrXBhpMMm/Listeners', 09:04:41 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 09:04:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:41 INFO - 'USER': 'cltbld', 09:04:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:41 INFO - 'XPC_FLAGS': '0x0', 09:04:41 INFO - 'XPC_SERVICE_NAME': '0', 09:04:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:41 INFO - Downloading/unpacking blobuploader==1.2.4 09:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:44 INFO - Downloading blobuploader-1.2.4.tar.gz 09:04:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:04:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:04:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:45 INFO - Downloading docopt-0.6.1.tar.gz 09:04:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:04:45 INFO - Installing collected packages: blobuploader, requests, docopt 09:04:45 INFO - Running setup.py install for blobuploader 09:04:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:04:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:04:45 INFO - Running setup.py install for requests 09:04:46 INFO - Running setup.py install for docopt 09:04:46 INFO - Successfully installed blobuploader requests docopt 09:04:46 INFO - Cleaning up... 09:04:46 INFO - Return code: 0 09:04:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:04:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:46 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 0x106135a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8d3cacca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, '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 0x105dfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106091b20>, '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.mCMUmqEzjd/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.wyrXBhpMMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:04:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:04:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:04:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mCMUmqEzjd/Render', 09:04:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:46 INFO - 'HOME': '/Users/cltbld', 09:04:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:46 INFO - 'LOGNAME': 'cltbld', 09:04:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:46 INFO - 'MOZ_NO_REMOTE': '1', 09:04:46 INFO - 'NO_EM_RESTART': '1', 09:04:46 INFO - 'PAGER': '/bin/cat', 09:04:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:46 INFO - 'PWD': '/builds/slave/test', 09:04:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:46 INFO - 'SHELL': '/bin/bash', 09:04:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wyrXBhpMMm/Listeners', 09:04:46 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 09:04:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:46 INFO - 'USER': 'cltbld', 09:04:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:46 INFO - 'XPC_FLAGS': '0x0', 09:04:46 INFO - 'XPC_SERVICE_NAME': '0', 09:04:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:04:46 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-4xrTO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:04:46 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-HsLpug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:04:46 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-U0c5nW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:04:46 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-13Uaay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:04:47 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-S8JUi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:04:47 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-V_uLkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:04:47 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-Yd8KLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:04:47 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-Rg0LzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:04:47 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-jicUvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:04:47 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-xxGhOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:04:47 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-Phv2oD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:04:47 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-JIAVWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:04:47 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-PTwY7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:04:48 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-jQTFZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:04:48 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-q6cM7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:04:48 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-KWfgg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:04:48 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-TnqHjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:04:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:04:48 INFO - Running setup.py install for manifestparser 09:04:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:04:48 INFO - Running setup.py install for mozcrash 09:04:48 INFO - Running setup.py install for mozdebug 09:04:48 INFO - Running setup.py install for mozdevice 09:04:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:04:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:04:49 INFO - Running setup.py install for mozfile 09:04:49 INFO - Running setup.py install for mozhttpd 09:04:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:04:49 INFO - Running setup.py install for mozinfo 09:04:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:04:49 INFO - Running setup.py install for mozInstall 09:04:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:04:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:04:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:04:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:04:49 INFO - Running setup.py install for mozleak 09:04:49 INFO - Running setup.py install for mozlog 09:04:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:04:49 INFO - Running setup.py install for moznetwork 09:04:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:04:50 INFO - Running setup.py install for mozprocess 09:04:50 INFO - Running setup.py install for mozprofile 09:04:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:04:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:04:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:04:50 INFO - Running setup.py install for mozrunner 09:04:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:04:50 INFO - Running setup.py install for mozscreenshot 09:04:50 INFO - Running setup.py install for moztest 09:04:50 INFO - Running setup.py install for mozversion 09:04:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:04:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:04:51 INFO - Cleaning up... 09:04:51 INFO - Return code: 0 09:04:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:51 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 0x106135a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8d3cacca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, '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 0x105dfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106091b20>, '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.mCMUmqEzjd/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.wyrXBhpMMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:04:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:04:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:04:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mCMUmqEzjd/Render', 09:04:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:51 INFO - 'HOME': '/Users/cltbld', 09:04:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:51 INFO - 'LOGNAME': 'cltbld', 09:04:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:51 INFO - 'MOZ_NO_REMOTE': '1', 09:04:51 INFO - 'NO_EM_RESTART': '1', 09:04:51 INFO - 'PAGER': '/bin/cat', 09:04:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:51 INFO - 'PWD': '/builds/slave/test', 09:04:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:51 INFO - 'SHELL': '/bin/bash', 09:04:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wyrXBhpMMm/Listeners', 09:04:51 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 09:04:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:51 INFO - 'USER': 'cltbld', 09:04:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:51 INFO - 'XPC_FLAGS': '0x0', 09:04:51 INFO - 'XPC_SERVICE_NAME': '0', 09:04:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:04:51 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-FMP9xH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:04:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:04:51 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-HiL4me-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:04:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:04:51 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-0xGNcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:04:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:04:51 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-cPPJJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:04:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:04:51 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-2RDIfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:04:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:04:51 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-2IE5tK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:04:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:04:51 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-63trJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:04:52 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-vWvjVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:04:52 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-fpDXnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:04:52 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-eo98ow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:04:52 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-_LZMi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:04:52 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-rJtyX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:04:52 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-x2Y5oF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:04:52 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-Xmnw0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:04:52 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-H9wXPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:04:52 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-_FL9hO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:04:53 INFO - Running setup.py (path:/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/pip-AnWeXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:04:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:55 INFO - Downloading blessings-1.6.tar.gz 09:04:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:04:55 INFO - Installing collected packages: blessings 09:04:55 INFO - Running setup.py install for blessings 09:04:55 INFO - Successfully installed blessings 09:04:55 INFO - Cleaning up... 09:04:55 INFO - Return code: 0 09:04:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:04:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:04:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:04:56 INFO - Reading from file tmpfile_stdout 09:04:56 INFO - Current package versions: 09:04:56 INFO - blessings == 1.6 09:04:56 INFO - blobuploader == 1.2.4 09:04:56 INFO - docopt == 0.6.1 09:04:56 INFO - functools32 == 3.2.3-2 09:04:56 INFO - jsonschema == 2.5.1 09:04:56 INFO - manifestparser == 1.1 09:04:56 INFO - mozInstall == 1.12 09:04:56 INFO - mozcrash == 0.17 09:04:56 INFO - mozdebug == 0.1 09:04:56 INFO - mozdevice == 0.48 09:04:56 INFO - mozfile == 1.2 09:04:56 INFO - mozhttpd == 0.7 09:04:56 INFO - mozinfo == 0.9 09:04:56 INFO - mozleak == 0.1 09:04:56 INFO - mozlog == 3.2 09:04:56 INFO - moznetwork == 0.27 09:04:56 INFO - mozprocess == 0.23 09:04:56 INFO - mozprofile == 0.28 09:04:56 INFO - mozrunner == 6.12 09:04:56 INFO - mozscreenshot == 0.1 09:04:56 INFO - mozsystemmonitor == 0.3 09:04:56 INFO - moztest == 0.7 09:04:56 INFO - mozversion == 1.4 09:04:56 INFO - psutil == 3.1.1 09:04:56 INFO - requests == 1.2.3 09:04:56 INFO - wsgiref == 0.1.2 09:04:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:04:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:56 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 0x106135a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8d3cacca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, '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 0x105dfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106091b20>, '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.mCMUmqEzjd/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.wyrXBhpMMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:04:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:04:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:04:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mCMUmqEzjd/Render', 09:04:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:56 INFO - 'HOME': '/Users/cltbld', 09:04:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:56 INFO - 'LOGNAME': 'cltbld', 09:04:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:56 INFO - 'MOZ_NO_REMOTE': '1', 09:04:56 INFO - 'NO_EM_RESTART': '1', 09:04:56 INFO - 'PAGER': '/bin/cat', 09:04:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:56 INFO - 'PWD': '/builds/slave/test', 09:04:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:56 INFO - 'SHELL': '/bin/bash', 09:04:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wyrXBhpMMm/Listeners', 09:04:56 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 09:04:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:56 INFO - 'USER': 'cltbld', 09:04:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:56 INFO - 'XPC_FLAGS': '0x0', 09:04:56 INFO - 'XPC_SERVICE_NAME': '0', 09:04:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:04:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:04:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:04:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:04:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:04:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:04:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:04:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:04:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:04:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:04:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:04:56 INFO - Cleaning up... 09:04:56 INFO - Return code: 0 09:04:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:04:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:56 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 0x106135a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8d3cacca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, '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 0x105dfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106091b20>, '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.mCMUmqEzjd/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.wyrXBhpMMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:04:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:04:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:04:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mCMUmqEzjd/Render', 09:04:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:56 INFO - 'HOME': '/Users/cltbld', 09:04:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:56 INFO - 'LOGNAME': 'cltbld', 09:04:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:56 INFO - 'MOZ_NO_REMOTE': '1', 09:04:56 INFO - 'NO_EM_RESTART': '1', 09:04:56 INFO - 'PAGER': '/bin/cat', 09:04:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:56 INFO - 'PWD': '/builds/slave/test', 09:04:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:56 INFO - 'SHELL': '/bin/bash', 09:04:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wyrXBhpMMm/Listeners', 09:04:56 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 09:04:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:56 INFO - 'USER': 'cltbld', 09:04:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:56 INFO - 'XPC_FLAGS': '0x0', 09:04:56 INFO - 'XPC_SERVICE_NAME': '0', 09:04:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:04:56 INFO - Cleaning up... 09:04:56 INFO - Return code: 0 09:04:56 INFO - Running post-action listener: _resource_record_post_action 09:04:56 INFO - Running post-action listener: _start_resource_monitoring 09:04:56 INFO - Starting resource monitoring. 09:04:56 INFO - ##### 09:04:56 INFO - ##### Finished create-virtualenv step (success) 09:04:56 INFO - ##### 09:04:56 INFO - ##### 09:04:56 INFO - ##### Running install step. 09:04:56 INFO - ##### 09:04:56 INFO - Running pre-action listener: _resource_record_pre_action 09:04:56 INFO - Running main action method: install 09:04:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:04:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:04:56 INFO - Reading from file tmpfile_stdout 09:04:56 INFO - Detecting whether we're running mozinstall >=1.0... 09:04:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:04:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:04:57 INFO - Reading from file tmpfile_stdout 09:04:57 INFO - Output received: 09:04:57 INFO - Usage: mozinstall [options] installer 09:04:57 INFO - Options: 09:04:57 INFO - -h, --help show this help message and exit 09:04:57 INFO - -d DEST, --destination=DEST 09:04:57 INFO - Directory to install application into. [default: 09:04:57 INFO - "/builds/slave/test"] 09:04:57 INFO - --app=APP Application being installed. [default: firefox] 09:04:57 INFO - mkdir: /builds/slave/test/build/application 09:04:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:05:31 INFO - Reading from file tmpfile_stdout 09:05:31 INFO - Output received: 09:05:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:05:31 INFO - Running post-action listener: _resource_record_post_action 09:05:31 INFO - ##### 09:05:31 INFO - ##### Finished install step (success) 09:05:31 INFO - ##### 09:05:31 INFO - ##### 09:05:31 INFO - ##### Running run-tests step. 09:05:31 INFO - ##### 09:05:31 INFO - Running pre-action listener: _resource_record_pre_action 09:05:31 INFO - Running main action method: run_tests 09:05:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:05:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:05:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:05:31 INFO - 2016-07-07 09:05:31.131 screenresolution[2219:28800] starting screenresolution argv=screenresolution get 09:05:31 INFO - 2016-07-07 09:05:31.134 screenresolution[2219:28800] Display 0: 1600x1200x32@60 09:05:31 INFO - 2016-07-07 09:05:31.139 screenresolution[2220:28803] starting screenresolution argv=screenresolution list 09:05:31 INFO - Available Modes on Display 0 09:05:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:05:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:05:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:05:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:05:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:05:31 INFO - Intel Iris: 09:05:31 INFO - Chipset Model: Intel Iris 09:05:31 INFO - Type: GPU 09:05:31 INFO - Bus: Built-In 09:05:31 INFO - VRAM (Dynamic, Max): 1536 MB 09:05:31 INFO - Vendor: Intel (0x8086) 09:05:31 INFO - Device ID: 0x0a2e 09:05:31 INFO - Revision ID: 0x0009 09:05:31 INFO - Displays: 09:05:31 INFO - Display: 09:05:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:05:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:05:31 INFO - Display Serial Number: 1600x1200 60 09:05:31 INFO - Main Display: Yes 09:05:31 INFO - Mirror: Off 09:05:31 INFO - Online: Yes 09:05:31 INFO - Rotation: Supported 09:05:31 INFO - Return code: 0 09:05:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:05:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:05:31 INFO - Python 2.7.3 09:05:31 INFO - Return code: 0 09:05:31 INFO - grabbing minidump binary from tooltool 09:05:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:05:31 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 0x106099d30>, '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 0x105dfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106091b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:05:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:05:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:05:31 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:05:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:05:31 INFO - Return code: 0 09:05:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:05:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:05:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:05:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:05:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:05:31 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/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:05:31 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/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0045 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:05:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mCMUmqEzjd/Render', 09:05:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:31 INFO - 'HOME': '/Users/cltbld', 09:05:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:31 INFO - 'LOGNAME': 'cltbld', 09:05:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:05:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:05:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:31 INFO - 'MOZ_NO_REMOTE': '1', 09:05:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:05:31 INFO - 'NO_EM_RESTART': '1', 09:05:31 INFO - 'PAGER': '/bin/cat', 09:05:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:31 INFO - 'PWD': '/builds/slave/test', 09:05:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:05:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:31 INFO - 'SHELL': '/bin/bash', 09:05:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wyrXBhpMMm/Listeners', 09:05:31 INFO - 'TMPDIR': '/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/', 09:05:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:31 INFO - 'USER': 'cltbld', 09:05:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:31 INFO - 'XPC_FLAGS': '0x0', 09:05:31 INFO - 'XPC_SERVICE_NAME': '0', 09:05:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:31 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/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0045', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:05:31 INFO - mozversion application_buildid: 20160707070123 09:05:31 INFO - mozversion application_changeset: ba5ff277a3cd1a7f655f8e02a130a395e8f2a225 09:05:31 INFO - mozversion application_display_name: Nightly 09:05:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:05:31 INFO - mozversion application_name: Firefox 09:05:31 INFO - mozversion application_remotingname: firefox 09:05:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:05:31 INFO - mozversion application_vendor: Mozilla 09:05:31 INFO - mozversion application_version: 50.0a1 09:05:31 INFO - mozversion platform_buildid: 20160707070123 09:05:31 INFO - mozversion platform_changeset: ba5ff277a3cd1a7f655f8e02a130a395e8f2a225 09:05:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:05:31 INFO - mozversion platform_version: 50.0a1 09:05:31 INFO - using testdate: 1467907531 09:05:31 INFO - actual date: 1467907531 09:05:31 INFO - starting webserver on 'localhost:59507' 09:05:31 INFO - SUITE-START | Running 2 tests 09:05:31 INFO - TEST-START | dromaeo_css 09:05:31 INFO - operating with platform_type : mac_ 09:05:31 INFO - Initialising browser for dromaeo_css test... 09:05:31 INFO - TEST-INFO | started process 2230 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/tmppZUOHF/profile http://localhost:59507/getInfo.html) 09:05:33 INFO - PROCESS | 2230 | __metrics Screen width/height:1600/1200 09:05:33 INFO - PROCESS | 2230 | colorDepth:24 09:05:33 INFO - PROCESS | 2230 | Browser inner width/height: 1024/689 09:05:33 INFO - PROCESS | 2230 | __metrics 09:05:34 INFO - PROCESS | 2230 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:05:34 INFO - PROCESS | 2230 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:05:34 INFO - TEST-INFO | 2230: exit 0 09:05:34 INFO - Browser initialized. 09:05:34 INFO - Running cycle 1/1 for dromaeo_css test... 09:05:34 INFO - TEST-INFO | started process 2232 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/tmppZUOHF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:05:46 INFO - PROCESS | 2232 | RSS: Main: 243601408 09:05:46 INFO - PROCESS | 2232 | 09:06:37 INFO - PROCESS | 2232 | 1467907597568 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:08:49 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:59507/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59507/tests/dromaeo/cssquery-ext.html) 09:08:50 INFO - PROCESS | 2232 | RSS: Main: 364503040 09:08:50 INFO - PROCESS | 2232 | 09:11:53 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:59507/tests/dromaeo/cssquery-ext.html (next: http://localhost:59507/tests/dromaeo/cssquery-jquery.html) 09:11:54 INFO - PROCESS | 2232 | RSS: Main: 383483904 09:11:54 INFO - PROCESS | 2232 | 09:14:56 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:59507/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59507/tests/dromaeo/cssquery-mootools.html) 09:14:57 INFO - PROCESS | 2232 | RSS: Main: 375504896 09:14:57 INFO - PROCESS | 2232 | 09:17:59 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:59507/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59507/tests/dromaeo/cssquery-prototype.html) 09:18:00 INFO - PROCESS | 2232 | RSS: Main: 373022720 09:18:00 INFO - PROCESS | 2232 | 09:21:03 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:59507/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59507/tests/dromaeo/cssquery-yui.html) 09:21:04 INFO - PROCESS | 2232 | RSS: Main: 337629184 09:21:04 INFO - PROCESS | 2232 | 09:24:07 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:59507/tests/dromaeo/cssquery-yui.html (next: http://localhost:59507/tests/dromaeo/cssquery-dojo.html) 09:24:07 INFO - PROCESS | 2232 | RSS: Main: 391249920 09:24:07 INFO - PROCESS | 2232 | 09:24:07 INFO - PROCESS | 2232 | __start_tp_report 09:24:07 INFO - PROCESS | 2232 | _x_x_mozilla_page_load 09:24:07 INFO - PROCESS | 2232 | _x_x_mozilla_page_load_details 09:24:07 INFO - PROCESS | 2232 | |i|pagename|runs| 09:24:07 INFO - PROCESS | 2232 | |0;dojo.html;3338.1988322802517;3342.715869151122;3362.983185084075;3388.728901687862;3394.796312221267;5462.6995515246745;5593.105103183487;5602.719864006794;5643.915341269879;5681.829545113643;5617.859553511164;5623.212750214963;5649.830505084844;5656.632318899268;5659.886802263953;5134.9229761553515;5157.123289040854;5167.48325167484;5188.558972487324;5215.791368345262;5212.791488340462;5216.66091704038;5226.947730522703;5229.50319719626;5229.581633469333;5139.0749665060275;5183.53348198662;5186.118359878829;5201.973990130044;5216.191490319006;5221.190715439113;5239.842804715865;5242.97378513105;5258.316418865561;5289.735513224324;4254.404383386328;5073.289739436482;5089.643724939218;5099.69401835891;5206.401263854629;5572.192032155314;5632.35227948787;5635.985522605929;5652.378238393773;5657.2928383952;6193.473554747852;6204.937950620481;6228.532860035471;6228.5951413158;6237.469815065725;6085.786997455112;6118.010103775202;6247.937520624781;6275.8744825103695;6315.652639104847;5743.08110702294;5763.221965034751;5770.769169233267;5784.797532086357;5796.333421656522;6329.525285603506;6331.556791024676;6333.1450254216015;6346.460550853137;6358.936410635927;5827.825165245049;5899.823005309848;6002.519798416116;6093.992595246657;6101.989540302799;6173.290071641729;6186.752529898844;6190.969045154746;6192.969035154885;6197.96901015492;6095.270276985446;6110.589135957139;6118.253719382578;6126.061440118077;6126.426068710516;5974.3428222895445;6100.369238098956;6106.1146133811235;6125.601835880653;6146.200993870858;4901.995091006335;4919.0407870464915;4938.456769755324;4948.802047918115;4949.034938187019;4409.07409444014;4447.266201076851;4477.686561940633;4487.573680500347;4546.977265113719;5637.23897273871;5643.830685079454;5672.234248376499;5676.829695109153;5727.39862314451;6083.452489275986;6212.4408803207925;6292.46514046302;6293.590916590407;6302.65335406557;5574.665520068809;5603.887922241533;5625.803096891589;5665.66006039639;5668.858278543069;5365.141577347606;5388.326459192601;5393.676379417169;5393.892122157614;5397.568194544427;6226.377362263737;6266.530010249249;6267.592606480564;6282.717277722605;6331.430171284606;6118.43644382056;6122.550631082853;6217.316095229611;6226.968865155645;6299.559030867796;6268.811935641938;6270.372962703693;6275.905861412174;6288.025356069817;6304.18045654062;5332.466753324637;5935.931532324224;5953.434423729739;6123;6150.323464419;5123.3339665843205;6137.376985335611;6210.254769427697;6256.436920677161;6269.655168965751;5967.262794824342;6005.7297421617;6043.214382130295;6102.237220347457;6110.572259941761;5788.623739456922;5926.644401335933;6037.547183961308;6056.424639659225;6100.1459795629535;5823.388544202967;5833.649981001154;5871.559633027455;5943.7028148591535;5945.256842894638;5942.227510423791;6036.154938308552;6039.275286965592;6046.909296360646;6059.515238780975;5748.281464816898;5838.299404071538;5843.181954526284;5886;5906.379830117776 09:24:07 INFO - PROCESS | 2232 | |1;ext.html;503.3959248901318;512.1268237654797;518.4271380124961;527.6622961304764;556.9053260945639;29589.55615665764;30677.539836902437;41528.792356038175;42463.78768106163;42471.787641061754;43469.56530434695;43806;43825.68522944317;43917.78041109798;43918;9687.660931867385;9868.309218354681;9868.555914983806;9876.913539510648;9910.058544438303;8586.570671466432;8810.163034511712;11912.880871191308;12084.516619335216;12085.879141208607;12059.939700301486;12060.517579296818;12108.97073748728;12114.21257618252;12119.212251203688;8647.135286471366;11859.229150105215;11924.523019079314;11937.224080434788;12017.158798884082;11633.47649355781;11963.282203067844;11996.220245684002;11996.880031199664;11998.400079995965;41040.79479602613;41421;41529.584704152876;41592.96017599554;41598.58401415977;3489.197484578561;3504.351694936445;3508.982455087734;3650.233450975272;3803.3344164771056;31661.366772664416;32032;32239.032829015166;32282.354352913044;32592;3278.47544392896;3291.144302481348;3294.390537750524;3328.1845947742718;3449.3963556377535;509538;509653;510397;510656;512264;61648.38351616516;62015;62092;62131.16557178707;62153.68923155355;35243.82378088093;35267.4709879352;35310.47034294488;36618.63381366152;36682.44976325357;16670.99974001564;16738.414155504743;16873;16886.240119194666;16898.239579218964;11621.709457263636;11828.88171118295;12716.618501444971;12842.422091005927;12962.935185324013;19456.141744795714;19458.10116901559;19500.27618673228;19570.70643940342;19663.836977932417;7833.882491762628;7842.294193522496;7853.09689385728;7867.921320786719;8015.559144247122;898.2634239923331;1014.4572653630272;1018.8318927377048;1027.9588816447408;1115.9330440173615;30448.54327185094;30604.54093188604;30770.38459230803;31034;31043.068707938797;934.2525979216599;1070.2026989892424;1077.5582011375297;1101.9338839669645;1163.4648061891626;16165;16166.676666466605;16167.029978201346;16517.091559964076;16618;697297;968171;990904;991005.0449747705;995139;61475;61831;62544.56004919682;62559;62820.68589657023;1269.60642200918;1317.7891537353978;1407.8240219972504;1413.0673755321438;1442.7980082788417;25151;30928.69071309258;31001.379972399973;32157.517637235927;32276;57571;57992.71003644955;58580.70709646425;58606;58708.11937820851;656156;661857;662182;662535;663429.0485642815;24005;24327.878360608083;24349.56504349551;26355.4728905417;27317.44873166555;38436.23127537489;39318.41022384609;47276.7636161817;47309.290360645304;48740;38650.42024369581;39833.4149926508;40064;40124.19751604893;41141.58858411497;37094.8145259272;37223.069423264635;39625.207495850496;40385.798071010635;40919.795401022806;37671.43492847664;38124;38634.6136538631;38832;38842.805785970886;33364.999050028535;34289.31421371608;35115.82442087875;35190.648093518736;35297.823510882285;20289.49276268105;25464.108756193444;25464.745352546237;25849.741502584733;28150 09:24:07 INFO - PROCESS | 2232 | |2;jquery.html;357.4548813060085;377.9074126838924;388.4833171881397;389.585091877151;410.28200648864487;15889.119310128744;17141.400050998218;20679.483012924647;21572.8921355393;21580.136794528218;16898.408555700553;20578.89710551449;20794.272200472948;20891.686624700604;20938.267160649342;5749.223854779614;5817.738201780909;6052.485538729214;6089.78685745999;6092.51259899207;5884.264466941632;5929.495992840592;5986.132010858423;5991.071383935475;6004.519638428916;5687.943120568803;5945.108233764927;5950.404959504058;5987.461128498434;6023.72893219804;5961.970190149043;5998.220231369915;6008.48927841134;6023.126646636235;6027.939720602782;5048.848534543933;5119.385673719177;5206.739663016834;5216.817411390621;5221.895562088775;24462.26613201607;25175.118870839433;25253.62119568208;25565.488690226277;25917.61123583148;1943.6598595245775;2275.8293128015466;2441.1333976438286;2496.500699860017;3089.3821235752716;14311.713765724731;15301.770473442908;15682.059076455449;15887.767044214754;15909.52271431859;1610.3317123393776;1747.8339557741995;1802.9639407211914;1830.752848365467;1840.4938641873457;115106.84893151045;116040;116114.41942790232;117006;118512;10584.994425529563;10848.945755271174;11073.947974942368;11082.556697732161;11264.155188360906;13589;13660.453581856813;13684.178949263076;13694.246816424993;13704.65738356549;8436.831263374697;8628.611712472954;8677.869831952527;8758.080401557872;8775.91224087751;8732.039475657673;8732.991411975281;8733.475991440408;8863.822723545492;8883.111688831064;6614.966925165343;6741.157355330583;6749.797506074826;6750;6755.527113102035;1615.87073034157;1628.9266982985794;1789.5078853315442;1821.298799962024;1828.542864283929;891.5675897189858;939.2532936315646;952.6094301336419;1022.8925962773801;1026.6919924022764;21214.46963825917;21436.892815536135;21539.784602153777;21569;21859.453513661967;934.1125930366179;962.9855552166723;964.3731574476506;979.5004547680734;995.2286977592424;9839.163671087894;11447.348767737118;11675;11807.763844723057;11882.702932426759;566617;569337;607437;629480;632793.8360308261;113811.43094284476;117579.41210293894;118034.40982795203;120000.3986326907;122602;992.9404235745734;1009.7172791618506;1017.0947856407803;1030.0523518363125;1032.963846265377;19519.414417567496;19803.40589782309;19877.900610497432;19909.800901990795;19957.101930412875;42173.578264216965;50632.493675062775;53822.461775381744;54768.45231547634;56027;53613;75751.62124189564;77081;78792;79085;10437.582496700046;11688.824667630162;11885.881141188478;12304.384780760747;12336.444859981142;13582.660433489242;14066.085704429179;14118.2940852959;14208.78686819678;14280.714385712436;10766.676999690022;11907.285562866256;12068;12159.817602736142;12204.877951220375;11082.279651822606;11868.169228153947;11881.5841445549;11914.702132446759;12187.390630468264;11962.521499139937;11987.40062996864;12091.395430228278;12217.633470995885;12219.51121955112;12276.81584776247;12306.753864922472;12367.443465043914;12397.690057748629;12494.625161245178;11929.522819087486;12024.519019239133;12027.977621901886;12207.938960305142;12228.87771122313 09:24:07 INFO - PROCESS | 2232 | |3;mootools.html;1368.1585824717802;1496.1920562896037;1551.9922400388;1747.3098126240136;1914.8851068935865;7809.726659566916;8234.83530329393;8245.670173193064;8248.13394593568;8250.628721707522;8056.355491560676;8435.409521333493;8686.348523860719;8695.652173913051;8848.734537963872;2325.534893021394;2337.345543247893;2368.692070030894;2369.9052037918464;2371.7865392114704;2346.2022912209845;2363.7518060603647;2384.153625462962;2388.176079252655;2394.8802559872024;2378.5599664062206;2381.20229723043;2386.0217310902535;2398.328468028955;2398.6282126270457;2014.9395518134406;2367.763223677636;2371.7746814052725;2377.9405514862265;2383.21353953195;2320.8027317678016;2353.458704497958;2354.164271683545;2355.6348765941307;2357.787799098089;8808;8810.911890881072;8875.86686199701;8883.978342490567;8889.377743557889;5179.093658609729;5211.088059589557;5368.62419630626;5373.543248823855;5376.1398176291605;3531.487934249545;3577.9821100894587;3659.6340365963456;3666.156783939709;3673.5591728992426;1093.103655002898;1099.312929419113;1102.2229328323513;1116.2465335898316;1118.289885922437;9779.364341317792;11664.35846028468;11686.590969316034;11690.123240756891;11693.70765730855;4462.129884672523;4480.372747815309;4651.651126165551;4654.790534425959;4655.255159174516;9203.35576509638;9216.262698984065;9225.58484868169;9235;9242.983271840092;5239.659422137549;5250.842474725764;5253.605979551549;5257.658252213594;5266.3943646480375;4340.435743353344;4396.516383197845;4529.184746745552;4893.388326459192;4914.483979182206;3284.9488163787837;3301.8844340447968;3819.8472061117245;3844.6347596978244;3864.5073927317285;1157.89578937896;1169.1640477058943;1173.2256710570982;1183.3018519073787;1185.911056670736;830.6303694855732;855.6662901468433;888.4757992784288;907.8728977943093;957.004715096306;6031.909521357183;6115.571909966348;6163.630182189083;6182.814515564541;6235.501159907197;887.8046829697586;899.5606681029142;956.9282303827241;957.4781743949565;1003.7741508160751;3481.060113769268;3513.297340531904;3926.2147570485445;3970.3647416413237;4002.4396584478195;10444.843327350096;10883.564657413615;10884;10897.182711296684;10902.5093870776;10078.14335781452;10647.14822814173;10820.621278255381;10864.837027444595;10878.728031799268;8128.146544612849;8177.345812334999;8320.583970801435;8327.084020757711;8338.332933365316;4944.505549445171;5890.263717035371;6010.87978240424;6137.693115344304;6200.418262472221;9823.066808653166;9850.458224797418;10115.443650599287;10182.898171018194;10216.795664086825;8011.3590912728005;10227.693169204937;10314.896851031694;10334.379937203792;10341.586336546454;7603.9619801900635;7703.345215656732;7781.494202876795;7795.493292935941;7797.7660670179985;7990.161033091675;8301.252887240178;8336.458130221516;8419.242268195892;8457.746267611981;7397.112346518237;7409.592472414069;7450.739224127129;7469.962650186714;7657.578833164007;7375.96312018458;7380.5940673263485;7469.925300746923;7512.887306690294;7520.962395187989;6784.423324017514;6872.41584465306;6883.552569082994;6885.758998435231;6894.172699276119;4673.205555055581;4847.442544107473;4929.359183306147;4956.851294461172;5064.34163558735;4272.320741700258;4568.520305367889;4870.439899411613;4930.580900623487;5045.62157838171 09:24:07 INFO - PROCESS | 2232 | |4;prototype.html;301.3520930000499;303.3720199187681;311.7957737681818;317.3684368107465;319.3899651665317;4366;4374.825006999717;5338.306020217371;5388.326459192601;5424.159255315428;4401.823927042922;4990.800367985276;5014.724190169539;5143.665661731985;5293.920591191135;3537.434010558311;3930.8820735377985;4054.209429161315;4102.835886564534;4125.381192821086;3380.1380647934743;3580.53453051103;4075.7554546727292;4112.91774164518;4116.917661646765;3738.7943663098517;3859;4053.797310134496;4054.797260136996;4088.2232375848644;4031.455753473302;4053.4730485036903;4056.8782936511952;4068.593140685937;4091.468109145822;3383.272596391763;3545.4504551794257;3546.8758593449365;3555.2178520725656;3582.5521809773777;4713.670043096985;4790.568848803624;4996.775145118479;5164.380274367062;5197.5322220999915;3636.7454278200535;4415.984323605557;4540.568645978659;4540.841070562515;4573.954260457386;4992.101421744084;5415.702136382497;5523.4200408956885;5574.60977731559;5643.379228284885;3554.7867127972404;3853.84584616615;3854.4603755473977;3858.6913046956456;3860.4402361657685;5432.293801805779;5823.56323275756;5852.858692554953;5854.160732044955;5909.674967876765;3257.42994975883;3543.9645603543636;3544.4151714967256;3814.6948244140403;3885.96114038856;3462.688358047788;3945.6251656092627;3947.980260098681;3951.881443556698;3962.9405558916637;3956.8920702203427;4199.601037901394;4350.934735978963;4421.889452763707;4562.5665561771575;5361.249425080492;5578.609497335228;5592.7762889483965;5606.971965140148;5610.372991832404;1317.5652034828483;1325.8806707396382;1339.9665008374868;1351.3648585164956;1351.6620844788804;686.7870960002415;826.8222332198607;844.594594594598;851.9275861551714;895.4045559702724;4039.1315867088715;5035.777102654304;5109.6678715883345;5121.692698438105;5206.583473322125;4806.663533552688;5634.577406694514;5651.802186923438;5773.509251713567;5784.566157538202;4223.028703398236;4716.764161791897;4804;5077.619178561548;5162.380514338303;2516.8993240270183;2582.3544113971507;2597.3636459067648;2990.5813186153955;3030.196997795586;5468.890622187613;5682.431756824284;5840.7583790996405;5844.620099693591;5852.15143804146;5314.893702125936;5533.253010843565;5706.743196556165;6009.338972712998;6018.3680713524445;5840.2115714378015;5986.820395388146;6014.0377539593455;6030.4572588467245;6044.848878778066;5435.755391007493;5546.306711661043;5799.3040835100055;5867.501262392744;5881.206037185097;5429.53848922846;5718.399568045295;5739.799107031401;5752.338481074563;5755.482006619424;4940.92588611164;6050.818475445743;6075.2405949256345;6085.1785009023215;6092.482139018233;3163.434100120363;3910.941335880021;3916.5300163979364;3919.1769728356226;3924.3524818405785;5102.183650615883;5593.860153496195;5766.884662306814;5837.562182836219;5880.323762767165;5177.974110129425;5339.839804805862;5446.828931779528;5689.175069614966;5693.402192769865;5097.643164978416;5454.536364409069;5568.276124103841;5584.0227960106;5592;5069.467705890828;5193.480651934777;5401.459854014567;5513.917291240715;5514.889702205854;5286.841394758163;5336.893262134813;5603.702481982367;5611.775528978795;5625;4539.909201815879;5462.508374246178;5479.315085614298;5504.339479262514;5546.057170280981 09:24:07 INFO - PROCESS | 2232 | |5;yui.html;600.9008513595255;731.7768080735375;739.8631253218155;776.945613807033;802.1897883138029;13813.861861381383;14871.776923346144;16139;16158.192090395478;18247.726284105716;16974.40589579365;17033;17119.828801711978;17131.571710707238;17201.913990430032;717.8420747435582;725.6698202317959;746.238836386885;880.4145243413122;905.2757793764995;878.2096113497839;885.0883589902411;889.3507739350279;889.7063968890251;889.8086911314066;940.05994005994;943.1982814607563;943.7310366545536;945.3854994253755;945.6075728572669;741.0070505522599;929.5445231836419;943.2312665177858;964.6334392930712;966.1884017425326;690.9136357955256;699.3985172751429;700.5586480517242;700.8878579427318;701.9894701579481;15379.538613841603;15395.923020384938;15409.845901541064;15411.3835446582;15427.691446171013;14929.626759331104;14934.25328733559;15055.322510487054;15057.924710376377;15136.470223542123;10413.895861041368;11975.760484790342;12101.818472722916;12233.510659573607;12555.246685198917;3825.827837747308;3947.210557888434;3990.521137463523;4048.251073551373;4075.001624601962;86790;106398.93601063968;111059.33410998761;112480;112552.43723781328;11445.599404020819;11467.655970320904;11489.195756297146;11530.827037594443;12118.878811211951;24113.397165071012;24131.758682413303;24132.396690082536;24160.516789664107;24165.879170604036;8180.754577362689;8236.176382361835;8438.620262088221;8471.152884711602;8473.279771219515;4425.402570652985;4562.840300589463;4582.587567118975;4600.079984003212;4724.385829842167;3683.4843121962945;3830.5020347354666;3835.6931445484292;3839.212961342918;3845.8846234613006;623.4357906094991;661.7650733989365;668.8394785251504;676.7597502886503;692.705600119947;567.625367257608;568.457123447107;575.8790653962715;682.1984168601873;696.6621188723465;9016.59425325862;9303.162715355651;9493.525323733924;9515.191208747196;9642.469664168539;552.7264004317889;593.7535922592076;603.7705671844759;686.3691074217434;697.9406750426169;4712.835050773207;4718.693284936468;4728.40894888139;4729.787159577827;4771.880702982384;87324;120512.19231711532;127399;131726;155721;109569;129504;129644.98017668603;131038.34480827725;132441;111015;114540.42729786297;115593;116368.99999999831;116402.41798790952;9000.954995224982;9034.27725781949;9059.22999027055;9099.317551183556;9181.265498760216;49179.754101229264;53299.20051199313;61266;62247.6887615559;64297.67851160714;90921;95222.57166142653;95367.52316238375;95549;95963.56054659047;10276.845847312446;10627.25609207379;12053.457594408448;12066.818997715216;12883.871161288267;12654.493820247455;13152.934235328763;13506.72986540244;13522.323883805968;13584.93207533956;11770.704712580964;12202.705269577342;12526.561570344995;12584.119111662096;12999.805002924775;12044.494438195226;12092.637220883387;12171.33057681836;12323;12898.290594017419;10610.840837387292;12198.207116537396;12231.816522751988;12471.625851224479;12613.369331533204;10530.21023423231;10757.085647720032;10844;10908.890911090788;11112.333260004425;8508.787280318042;10373.792524149625;10846.29499082557;10916.454177290945;10925.235233533576 09:24:07 INFO - PROCESS | 2232 | __end_tp_report 09:24:07 INFO - PROCESS | 2232 | __start_cc_report 09:24:07 INFO - PROCESS | 2232 | _x_x_mozilla_cycle_collect,1213 09:24:07 INFO - PROCESS | 2232 | __end_cc_report 09:24:07 INFO - PROCESS | 2232 | __startTimestamp1467908647417__endTimestamp 09:24:07 INFO - PROCESS | 2232 | 09:24:07 INFO - PROCESS | 2232 | ------- Summary: start ------- 09:24:07 INFO - PROCESS | 2232 | Number of tests: 6 09:24:07 INFO - PROCESS | 2232 | 09:24:07 INFO - PROCESS | 2232 | [#0] dojo.html Cycles:165 Average:5687.57 Median:5838.30 stddev:628.63 (10.8%) stddev-sans-first:602.93 09:24:07 INFO - PROCESS | 2232 | Values: 3338.2 3342.7 3363.0 3388.7 3394.8 5462.7 5593.1 5602.7 5643.9 5681.8 5617.9 5623.2 5649.8 5656.6 5659.9 5134.9 5157.1 5167.5 5188.6 5215.8 5212.8 5216.7 5226.9 5229.5 5229.6 5139.1 5183.5 5186.1 5202.0 5216.2 5221.2 5239.8 5243.0 5258.3 5289.7 4254.4 5073.3 5089.6 5099.7 5206.4 5572.2 5632.4 5636.0 5652.4 5657.3 6193.5 6204.9 6228.5 6228.6 6237.5 6085.8 6118.0 6247.9 6275.9 6315.7 5743.1 5763.2 5770.8 5784.8 5796.3 6329.5 6331.6 6333.1 6346.5 6358.9 5827.8 5899.8 6002.5 6094.0 6102.0 6173.3 6186.8 6191.0 6193.0 6198.0 6095.3 6110.6 6118.3 6126.1 6126.4 5974.3 6100.4 6106.1 6125.6 6146.2 4902.0 4919.0 4938.5 4948.8 4949.0 4409.1 4447.3 4477.7 4487.6 4547.0 5637.2 5643.8 5672.2 5676.8 5727.4 6083.5 6212.4 6292.5 6293.6 6302.7 5574.7 5603.9 5625.8 5665.7 5668.9 5365.1 5388.3 5393.7 5393.9 5397.6 6226.4 6266.5 6267.6 6282.7 6331.4 6118.4 6122.6 6217.3 6227.0 6299.6 6268.8 6270.4 6275.9 6288.0 6304.2 5332.5 5935.9 5953.4 6123.0 6150.3 5123.3 6137.4 6210.3 6256.4 6269.7 5967.3 6005.7 6043.2 6102.2 6110.6 5788.6 5926.6 6037.5 6056.4 6100.1 5823.4 5833.6 5871.6 5943.7 5945.3 5942.2 6036.2 6039.3 6046.9 6059.5 5748.3 5838.3 5843.2 5886.0 5906.4 09:24:07 INFO - PROCESS | 2232 | 09:24:07 INFO - PROCESS | 2232 | [#1] ext.html Cycles:180 Average:81130.78 Median:35282.65 stddev:195457.54 (554.0%) stddev-sans-first:195912.10 09:24:07 INFO - PROCESS | 2232 | Values: 503.4 512.1 518.4 527.7 556.9 29589.6 30677.5 41528.8 42463.8 42471.8 43469.6 43806.0 43825.7 43917.8 43918.0 9687.7 9868.3 9868.6 9876.9 9910.1 8586.6 8810.2 11912.9 12084.5 12085.9 12059.9 12060.5 12109.0 12114.2 12119.2 8647.1 11859.2 11924.5 11937.2 12017.2 11633.5 11963.3 11996.2 11996.9 11998.4 41040.8 41421.0 41529.6 41593.0 41598.6 3489.2 3504.4 3509.0 3650.2 3803.3 31661.4 32032.0 32239.0 32282.4 32592.0 3278.5 3291.1 3294.4 3328.2 3449.4 509538.0 509653.0 510397.0 510656.0 512264.0 61648.4 62015.0 62092.0 62131.2 62153.7 35243.8 35267.5 35310.5 36618.6 36682.4 16671.0 16738.4 16873.0 16886.2 16898.2 11621.7 11828.9 12716.6 12842.4 12962.9 19456.1 19458.1 19500.3 19570.7 19663.8 7833.9 7842.3 7853.1 7867.9 8015.6 898.3 1014.5 1018.8 1028.0 1115.9 30448.5 30604.5 30770.4 31034.0 31043.1 934.3 1070.2 1077.6 1101.9 1163.5 16165.0 16166.7 16167.0 16517.1 16618.0 697297.0 968171.0 990904.0 991005.0 995139.0 61475.0 61831.0 62544.6 62559.0 62820.7 1269.6 1317.8 1407.8 1413.1 1442.8 25151.0 30928.7 31001.4 32157.5 32276.0 57571.0 57992.7 58580.7 58606.0 58708.1 656156.0 661857.0 662182.0 662535.0 663429.0 24005.0 24327.9 24349.6 26355.5 27317.4 38436.2 39318.4 47276.8 47309.3 48740.0 38650.4 39833.4 40064.0 40124.2 41141.6 37094.8 37223.1 39625.2 40385.8 40919.8 37671.4 38124.0 38634.6 38832.0 38842.8 33365.0 34289.3 35115.8 35190.6 35297.8 20289.5 25464.1 25464.7 25849.7 28150.0 09:24:07 INFO - PROCESS | 2232 | 09:24:07 INFO - PROCESS | 2232 | [#2] jquery.html Cycles:180 Average:35173.14 Median:20736.88 stddev:99961.15 (482.0%) stddev-sans-first:100207.38 09:24:07 INFO - PROCESS | 2232 | Values: 357.5 377.9 388.5 389.6 410.3 15889.1 17141.4 20679.5 21572.9 21580.1 16898.4 20578.9 20794.3 20891.7 20938.3 5749.2 5817.7 6052.5 6089.8 6092.5 5884.3 5929.5 5986.1 5991.1 6004.5 5687.9 5945.1 5950.4 5987.5 6023.7 5962.0 5998.2 6008.5 6023.1 6027.9 5048.8 5119.4 5206.7 5216.8 5221.9 24462.3 25175.1 25253.6 25565.5 25917.6 1943.7 2275.8 2441.1 2496.5 3089.4 14311.7 15301.8 15682.1 15887.8 15909.5 1610.3 1747.8 1803.0 1830.8 1840.5 115106.8 116040.0 116114.4 117006.0 118512.0 10585.0 10848.9 11073.9 11082.6 11264.2 13589.0 13660.5 13684.2 13694.2 13704.7 8436.8 8628.6 8677.9 8758.1 8775.9 8732.0 8733.0 8733.5 8863.8 8883.1 6615.0 6741.2 6749.8 6750.0 6755.5 1615.9 1628.9 1789.5 1821.3 1828.5 891.6 939.3 952.6 1022.9 1026.7 21214.5 21436.9 21539.8 21569.0 21859.5 934.1 963.0 964.4 979.5 995.2 9839.2 11447.3 11675.0 11807.8 11882.7 566617.0 569337.0 607437.0 629480.0 632793.8 113811.4 117579.4 118034.4 120000.4 122602.0 992.9 1009.7 1017.1 1030.1 1033.0 19519.4 19803.4 19877.9 19909.8 19957.1 42173.6 50632.5 53822.5 54768.5 56027.0 53613.0 75751.6 77081.0 78792.0 79085.0 10437.6 11688.8 11885.9 12304.4 12336.4 13582.7 14066.1 14118.3 14208.8 14280.7 10766.7 11907.3 12068.0 12159.8 12204.9 11082.3 11868.2 11881.6 11914.7 12187.4 11962.5 11987.4 12091.4 12217.6 12219.5 12276.8 12306.8 12367.4 12397.7 12494.6 11929.5 12024.5 12028.0 12207.9 12228.9 09:24:07 INFO - PROCESS | 2232 | 09:24:07 INFO - PROCESS | 2232 | [#3] mootools.html Cycles:180 Average:5649.99 Median:4903.94 stddev:3165.56 (64.6%) stddev-sans-first:3158.08 09:24:07 INFO - PROCESS | 2232 | Values: 1368.2 1496.2 1552.0 1747.3 1914.9 7809.7 8234.8 8245.7 8248.1 8250.6 8056.4 8435.4 8686.3 8695.7 8848.7 2325.5 2337.3 2368.7 2369.9 2371.8 2346.2 2363.8 2384.2 2388.2 2394.9 2378.6 2381.2 2386.0 2398.3 2398.6 2014.9 2367.8 2371.8 2377.9 2383.2 2320.8 2353.5 2354.2 2355.6 2357.8 8808.0 8810.9 8875.9 8884.0 8889.4 5179.1 5211.1 5368.6 5373.5 5376.1 3531.5 3578.0 3659.6 3666.2 3673.6 1093.1 1099.3 1102.2 1116.2 1118.3 9779.4 11664.4 11686.6 11690.1 11693.7 4462.1 4480.4 4651.7 4654.8 4655.3 9203.4 9216.3 9225.6 9235.0 9243.0 5239.7 5250.8 5253.6 5257.7 5266.4 4340.4 4396.5 4529.2 4893.4 4914.5 3284.9 3301.9 3819.8 3844.6 3864.5 1157.9 1169.2 1173.2 1183.3 1185.9 830.6 855.7 888.5 907.9 957.0 6031.9 6115.6 6163.6 6182.8 6235.5 887.8 899.6 956.9 957.5 1003.8 3481.1 3513.3 3926.2 3970.4 4002.4 10444.8 10883.6 10884.0 10897.2 10902.5 10078.1 10647.1 10820.6 10864.8 10878.7 8128.1 8177.3 8320.6 8327.1 8338.3 4944.5 5890.3 6010.9 6137.7 6200.4 9823.1 9850.5 10115.4 10182.9 10216.8 8011.4 10227.7 10314.9 10334.4 10341.6 7604.0 7703.3 7781.5 7795.5 7797.8 7990.2 8301.3 8336.5 8419.2 8457.7 7397.1 7409.6 7450.7 7470.0 7657.6 7376.0 7380.6 7469.9 7512.9 7521.0 6784.4 6872.4 6883.6 6885.8 6894.2 4673.2 4847.4 4929.4 4956.9 5064.3 4272.3 4568.5 4870.4 4930.6 5045.6 09:24:07 INFO - PROCESS | 2232 | 09:24:07 INFO - PROCESS | 2232 | [#4] prototype.html Cycles:180 Average:4513.97 Median:5105.93 stddev:1416.01 (27.7%) stddev-sans-first:1384.23 09:24:07 INFO - PROCESS | 2232 | Values: 301.4 303.4 311.8 317.4 319.4 4366.0 4374.8 5338.3 5388.3 5424.2 4401.8 4990.8 5014.7 5143.7 5293.9 3537.4 3930.9 4054.2 4102.8 4125.4 3380.1 3580.5 4075.8 4112.9 4116.9 3738.8 3859.0 4053.8 4054.8 4088.2 4031.5 4053.5 4056.9 4068.6 4091.5 3383.3 3545.5 3546.9 3555.2 3582.6 4713.7 4790.6 4996.8 5164.4 5197.5 3636.7 4416.0 4540.6 4540.8 4574.0 4992.1 5415.7 5523.4 5574.6 5643.4 3554.8 3853.8 3854.5 3858.7 3860.4 5432.3 5823.6 5852.9 5854.2 5909.7 3257.4 3544.0 3544.4 3814.7 3886.0 3462.7 3945.6 3948.0 3951.9 3962.9 3956.9 4199.6 4350.9 4421.9 4562.6 5361.2 5578.6 5592.8 5607.0 5610.4 1317.6 1325.9 1340.0 1351.4 1351.7 686.8 826.8 844.6 851.9 895.4 4039.1 5035.8 5109.7 5121.7 5206.6 4806.7 5634.6 5651.8 5773.5 5784.6 4223.0 4716.8 4804.0 5077.6 5162.4 2516.9 2582.4 2597.4 2990.6 3030.2 5468.9 5682.4 5840.8 5844.6 5852.2 5314.9 5533.3 5706.7 6009.3 6018.4 5840.2 5986.8 6014.0 6030.5 6044.8 5435.8 5546.3 5799.3 5867.5 5881.2 5429.5 5718.4 5739.8 5752.3 5755.5 4940.9 6050.8 6075.2 6085.2 6092.5 3163.4 3910.9 3916.5 3919.2 3924.4 5102.2 5593.9 5766.9 5837.6 5880.3 5178.0 5339.8 5446.8 5689.2 5693.4 5097.6 5454.5 5568.3 5584.0 5592.0 5069.5 5193.5 5401.5 5513.9 5514.9 5286.8 5336.9 5603.7 5611.8 5625.0 4539.9 5462.5 5479.3 5504.3 5546.1 09:24:07 INFO - PROCESS | 2232 | 09:24:07 INFO - PROCESS | 2232 | [#5] yui.html Cycles:180 Average:24133.56 Median:3837.45 stddev:37780.84 (984.5%) stddev-sans-first:37845.51 09:24:07 INFO - PROCESS | 2232 | Values: 600.9 731.8 739.9 776.9 802.2 13813.9 14871.8 16139.0 16158.2 18247.7 16974.4 17033.0 17119.8 17131.6 17201.9 717.8 725.7 746.2 880.4 905.3 878.2 885.1 889.4 889.7 889.8 940.1 943.2 943.7 945.4 945.6 741.0 929.5 943.2 964.6 966.2 690.9 699.4 700.6 700.9 702.0 15379.5 15395.9 15409.8 15411.4 15427.7 14929.6 14934.3 15055.3 15057.9 15136.5 10413.9 11975.8 12101.8 12233.5 12555.2 3825.8 3947.2 3990.5 4048.3 4075.0 86790.0 106398.9 111059.3 112480.0 112552.4 11445.6 11467.7 11489.2 11530.8 12118.9 24113.4 24131.8 24132.4 24160.5 24165.9 8180.8 8236.2 8438.6 8471.2 8473.3 4425.4 4562.8 4582.6 4600.1 4724.4 3683.5 3830.5 3835.7 3839.2 3845.9 623.4 661.8 668.8 676.8 692.7 567.6 568.5 575.9 682.2 696.7 9016.6 9303.2 9493.5 9515.2 9642.5 552.7 593.8 603.8 686.4 697.9 4712.8 4718.7 4728.4 4729.8 4771.9 87324.0 120512.2 127399.0 131726.0 155721.0 109569.0 129504.0 129645.0 131038.3 132441.0 111015.0 114540.4 115593.0 116369.0 116402.4 9001.0 9034.3 9059.2 9099.3 9181.3 49179.8 53299.2 61266.0 62247.7 64297.7 90921.0 95222.6 95367.5 95549.0 95963.6 10276.8 10627.3 12053.5 12066.8 12883.9 12654.5 13152.9 13506.7 13522.3 13584.9 11770.7 12202.7 12526.6 12584.1 12999.8 12044.5 12092.6 12171.3 12323.0 12898.3 10610.8 12198.2 12231.8 12471.6 12613.4 10530.2 10757.1 10844.0 10908.9 11112.3 8508.8 10373.8 10846.3 10916.5 10925.2 09:24:07 INFO - PROCESS | 2232 | -------- Summary: end -------- 09:24:07 INFO - PROCESS | 2232 | 09:24:08 INFO - TEST-INFO | 2232: exit 0 09:24:08 INFO - TEST-OK | dromaeo_css | took 1116351ms 09:24:08 INFO - TEST-START | kraken 09:24:08 INFO - operating with platform_type : mac_ 09:24:08 INFO - Initialising browser for kraken test... 09:24:08 INFO - TEST-INFO | started process 2234 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/tmpMP2aLN/profile http://localhost:59507/getInfo.html) 09:24:10 INFO - PROCESS | 2234 | __metrics Screen width/height:1600/1200 09:24:10 INFO - PROCESS | 2234 | colorDepth:24 09:24:10 INFO - PROCESS | 2234 | Browser inner width/height: 1024/689 09:24:10 INFO - PROCESS | 2234 | __metrics 09:24:10 INFO - PROCESS | 2234 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:24:10 INFO - PROCESS | 2234 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:24:11 INFO - TEST-INFO | 2234: exit 0 09:24:11 INFO - Browser initialized. 09:24:11 INFO - Running cycle 1/1 for kraken test... 09:24:11 INFO - TEST-INFO | started process 2235 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/T/tmpMP2aLN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:24:23 INFO - PROCESS | 2235 | RSS: Main: 243699712 09:24:23 INFO - PROCESS | 2235 | 09:24:25 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:59507/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59507/tests/kraken/driver.html?testName=audio-beat-detection) 09:24:26 INFO - PROCESS | 2235 | RSS: Main: 294281216 09:24:26 INFO - PROCESS | 2235 | 09:24:29 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:59507/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59507/tests/kraken/driver.html?testName=audio-dft) 09:24:29 INFO - PROCESS | 2235 | RSS: Main: 304267264 09:24:29 INFO - PROCESS | 2235 | 09:24:33 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:59507/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59507/tests/kraken/driver.html?testName=audio-fft) 09:24:34 INFO - PROCESS | 2235 | RSS: Main: 390742016 09:24:34 INFO - PROCESS | 2235 | 09:24:36 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:59507/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59507/tests/kraken/driver.html?testName=audio-oscillator) 09:24:37 INFO - PROCESS | 2235 | RSS: Main: 292433920 09:24:37 INFO - PROCESS | 2235 | 09:24:39 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:59507/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59507/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:24:39 INFO - PROCESS | 2235 | RSS: Main: 296484864 09:24:39 INFO - PROCESS | 2235 | 09:24:42 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:59507/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59507/tests/kraken/driver.html?testName=imaging-darkroom) 09:24:43 INFO - PROCESS | 2235 | RSS: Main: 332185600 09:24:43 INFO - PROCESS | 2235 | 09:24:46 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:59507/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59507/tests/kraken/driver.html?testName=imaging-desaturate) 09:24:46 INFO - PROCESS | 2235 | RSS: Main: 335388672 09:24:46 INFO - PROCESS | 2235 | 09:24:49 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:59507/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59507/tests/kraken/driver.html?testName=json-parse-financial) 09:24:50 INFO - PROCESS | 2235 | RSS: Main: 334131200 09:24:50 INFO - PROCESS | 2235 | 09:24:52 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:59507/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59507/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:24:52 INFO - PROCESS | 2235 | RSS: Main: 313937920 09:24:52 INFO - PROCESS | 2235 | 09:24:54 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:59507/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59507/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:24:55 INFO - PROCESS | 2235 | RSS: Main: 320204800 09:24:55 INFO - PROCESS | 2235 | 09:24:57 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:59507/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59507/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:24:58 INFO - PROCESS | 2235 | RSS: Main: 318140416 09:24:58 INFO - PROCESS | 2235 | 09:25:00 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:59507/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59507/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:25:01 INFO - PROCESS | 2235 | RSS: Main: 327454720 09:25:01 INFO - PROCESS | 2235 | 09:25:04 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:59507/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59507/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:25:05 INFO - PROCESS | 2235 | RSS: Main: 329498624 09:25:05 INFO - PROCESS | 2235 | 09:25:06 INFO - PROCESS | 2235 | Cycle 1(1): loaded http://localhost:59507/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59507/tests/kraken/driver.html?testName=ai-astar) 09:25:07 INFO - PROCESS | 2235 | RSS: Main: 373489664 09:25:07 INFO - PROCESS | 2235 | 09:25:07 INFO - PROCESS | 2235 | __start_tp_report 09:25:07 INFO - PROCESS | 2235 | _x_x_mozilla_page_load 09:25:07 INFO - PROCESS | 2235 | _x_x_mozilla_page_load_details 09:25:07 INFO - PROCESS | 2235 | |i|pagename|runs| 09:25:07 INFO - PROCESS | 2235 | |0;ai-astar;99;113;113;111;110;113;112;111;113;117 09:25:07 INFO - PROCESS | 2235 | |1;audio-beat-detection;143;109;107;162;107;106;128;104;159;104 09:25:07 INFO - PROCESS | 2235 | |2;audio-dft;187;188;183;195;266;184;180;185;183;183 09:25:07 INFO - PROCESS | 2235 | |3;audio-fft;78;75;61;133;79;80;62;59;118;108 09:25:07 INFO - PROCESS | 2235 | |4;audio-oscillator;84;92;91;72;70;67;98;69;68;100 09:25:07 INFO - PROCESS | 2235 | |5;imaging-gaussian-blur;87;85;88;86;87;84;88;85;87;84 09:25:07 INFO - PROCESS | 2235 | |6;imaging-darkroom;92;94;94;109;149;150;94;95;93;95 09:25:07 INFO - PROCESS | 2235 | |7;imaging-desaturate;84;84;85;107;139;86;127;141;119;84 09:25:07 INFO - PROCESS | 2235 | |8;json-parse-financial;56;55;54;55;53;54;55;56;54;53 09:25:07 INFO - PROCESS | 2235 | |9;json-stringify-tinderbox;66;64;74;80;72;68;69;69;69;71 09:25:07 INFO - PROCESS | 2235 | |10;stanford-crypto-aes;83;82;83;83;85;81;84;83;83;85 09:25:07 INFO - PROCESS | 2235 | |11;stanford-crypto-ccm;138;136;136;134;132;138;136;137;225;136 09:25:07 INFO - PROCESS | 2235 | |12;stanford-crypto-pbkdf2;167;164;163;168;220;219;164;172;226;194 09:25:07 INFO - PROCESS | 2235 | |13;stanford-crypto-sha256-iterative;67;67;67;67;66;66;67;67;66;66 09:25:07 INFO - PROCESS | 2235 | __end_tp_report 09:25:07 INFO - PROCESS | 2235 | __start_cc_report 09:25:07 INFO - PROCESS | 2235 | _x_x_mozilla_cycle_collect,2061 09:25:07 INFO - PROCESS | 2235 | __end_cc_report 09:25:07 INFO - PROCESS | 2235 | __startTimestamp1467908707211__endTimestamp 09:25:07 INFO - PROCESS | 2235 | 09:25:07 INFO - PROCESS | 2235 | ------- Summary: start ------- 09:25:07 INFO - PROCESS | 2235 | Number of tests: 14 09:25:07 INFO - PROCESS | 2235 | 09:25:07 INFO - PROCESS | 2235 | [#0] ai-astar Cycles:10 Average:111.20 Median:113.00 stddev:4.69 (4.1%) stddev-sans-first:2.01 09:25:07 INFO - PROCESS | 2235 | Values: 99.0 113.0 113.0 111.0 110.0 113.0 112.0 111.0 113.0 117.0 09:25:07 INFO - PROCESS | 2235 | 09:25:07 INFO - PROCESS | 2235 | [#1] audio-beat-detection Cycles:10 Average:122.90 Median:118.50 stddev:23.48 (19.8%) stddev-sans-first:23.75 09:25:07 INFO - PROCESS | 2235 | Values: 143.0 109.0 107.0 162.0 107.0 106.0 128.0 104.0 159.0 104.0 09:25:07 INFO - PROCESS | 2235 | 09:25:07 INFO - PROCESS | 2235 | [#2] audio-dft Cycles:10 Average:193.40 Median:186.00 stddev:25.83 (13.9%) stddev-sans-first:27.30 09:25:07 INFO - PROCESS | 2235 | Values: 187.0 188.0 183.0 195.0 266.0 184.0 180.0 185.0 183.0 183.0 09:25:07 INFO - PROCESS | 2235 | 09:25:07 INFO - PROCESS | 2235 | [#3] audio-fft Cycles:10 Average:85.30 Median:68.50 stddev:25.63 (37.4%) stddev-sans-first:27.05 09:25:07 INFO - PROCESS | 2235 | Values: 78.0 75.0 61.0 133.0 79.0 80.0 62.0 59.0 118.0 108.0 09:25:07 INFO - PROCESS | 2235 | 09:25:07 INFO - PROCESS | 2235 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:78.00 stddev:13.30 (17.0%) stddev-sans-first:14.06 09:25:07 INFO - PROCESS | 2235 | Values: 84.0 92.0 91.0 72.0 70.0 67.0 98.0 69.0 68.0 100.0 09:25:07 INFO - PROCESS | 2235 | 09:25:07 INFO - PROCESS | 2235 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.00 stddev:1.52 (1.8%) stddev-sans-first:1.58 09:25:07 INFO - PROCESS | 2235 | Values: 87.0 85.0 88.0 86.0 87.0 84.0 88.0 85.0 87.0 84.0 09:25:07 INFO - PROCESS | 2235 | 09:25:07 INFO - PROCESS | 2235 | [#6] imaging-darkroom Cycles:10 Average:106.50 Median:94.00 stddev:23.17 (24.6%) stddev-sans-first:23.97 09:25:07 INFO - PROCESS | 2235 | Values: 92.0 94.0 94.0 109.0 149.0 150.0 94.0 95.0 93.0 95.0 09:25:07 INFO - PROCESS | 2235 | 09:25:07 INFO - PROCESS | 2235 | [#7] imaging-desaturate Cycles:10 Average:105.60 Median:84.00 stddev:24.07 (28.7%) stddev-sans-first:24.23 09:25:07 INFO - PROCESS | 2235 | Values: 84.0 84.0 85.0 107.0 139.0 86.0 127.0 141.0 119.0 84.0 09:25:07 INFO - PROCESS | 2235 | 09:25:07 INFO - PROCESS | 2235 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.08 (2.0%) stddev-sans-first:1.00 09:25:07 INFO - PROCESS | 2235 | Values: 56.0 55.0 54.0 55.0 53.0 54.0 55.0 56.0 54.0 53.0 09:25:07 INFO - PROCESS | 2235 | 09:25:07 INFO - PROCESS | 2235 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:70.00 stddev:4.47 (6.4%) stddev-sans-first:4.47 09:25:07 INFO - PROCESS | 2235 | Values: 66.0 64.0 74.0 80.0 72.0 68.0 69.0 69.0 69.0 71.0 09:25:07 INFO - PROCESS | 2235 | 09:25:07 INFO - PROCESS | 2235 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:83.00 stddev:1.23 (1.5%) stddev-sans-first:1.30 09:25:07 INFO - PROCESS | 2235 | Values: 83.0 82.0 83.0 83.0 85.0 81.0 84.0 83.0 83.0 85.0 09:25:07 INFO - PROCESS | 2235 | 09:25:07 INFO - PROCESS | 2235 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:136.50 stddev:28.24 (20.7%) stddev-sans-first:29.84 09:25:07 INFO - PROCESS | 2235 | Values: 138.0 136.0 136.0 134.0 132.0 138.0 136.0 137.0 225.0 136.0 09:25:07 INFO - PROCESS | 2235 | 09:25:07 INFO - PROCESS | 2235 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.70 Median:183.00 stddev:26.43 (14.4%) stddev-sans-first:27.15 09:25:07 INFO - PROCESS | 2235 | Values: 167.0 164.0 163.0 168.0 220.0 219.0 164.0 172.0 226.0 194.0 09:25:07 INFO - PROCESS | 2235 | 09:25:07 INFO - PROCESS | 2235 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:0.52 (0.8%) stddev-sans-first:0.53 09:25:07 INFO - PROCESS | 2235 | Values: 67.0 67.0 67.0 67.0 66.0 66.0 67.0 67.0 66.0 66.0 09:25:07 INFO - PROCESS | 2235 | -------- Summary: end -------- 09:25:07 INFO - PROCESS | 2235 | 09:25:08 INFO - TEST-INFO | 2235: exit 0 09:25:08 INFO - TEST-OK | kraken | took 59996ms 09:25:08 INFO - SUITE-END | took 1176s 09:25:08 INFO - Completed test suite (00:19:36) 09:25:08 INFO - javascript benchmark 09:25:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3338.1988322802517, 3342.715869151122, 3362.983185084075, 3388.728901687862, 3394.796312221267, 5462.6995515246745, 5593.105103183487, 5602.719864006794, 5643.915341269879, 5681.829545113643, 5617.859553511164, 5623.212750214963, 5649.830505084844, 5656.632318899268, 5659.886802263953, 5134.9229761553515, 5157.123289040854, 5167.48325167484, 5188.558972487324, 5215.791368345262, 5212.791488340462, 5216.66091704038, 5226.947730522703, 5229.50319719626, 5229.581633469333, 5139.0749665060275, 5183.53348198662, 5186.118359878829, 5201.973990130044, 5216.191490319006, 5221.190715439113, 5239.842804715865, 5242.97378513105, 5258.316418865561, 5289.735513224324, 4254.404383386328, 5073.289739436482, 5089.643724939218, 5099.69401835891, 5206.401263854629, 5572.192032155314, 5632.35227948787, 5635.985522605929, 5652.378238393773, 5657.2928383952, 6193.473554747852, 6204.937950620481, 6228.532860035471, 6228.5951413158, 6237.469815065725, 6085.786997455112, 6118.010103775202, 6247.937520624781, 6275.8744825103695, 6315.652639104847, 5743.08110702294, 5763.221965034751, 5770.769169233267, 5784.797532086357, 5796.333421656522, 6329.525285603506, 6331.556791024676, 6333.1450254216015, 6346.460550853137, 6358.936410635927, 5827.825165245049, 5899.823005309848, 6002.519798416116, 6093.992595246657, 6101.989540302799, 6173.290071641729, 6186.752529898844, 6190.969045154746, 6192.969035154885, 6197.96901015492, 6095.270276985446, 6110.589135957139, 6118.253719382578, 6126.061440118077, 6126.426068710516, 5974.3428222895445, 6100.369238098956, 6106.1146133811235, 6125.601835880653, 6146.200993870858, 4901.995091006335, 4919.0407870464915, 4938.456769755324, 4948.802047918115, 4949.034938187019, 4409.07409444014, 4447.266201076851, 4477.686561940633, 4487.573680500347, 4546.977265113719, 5637.23897273871, 5643.830685079454, 5672.234248376499, 5676.829695109153, 5727.39862314451, 6083.452489275986, 6212.4408803207925, 6292.46514046302, 6293.590916590407, 6302.65335406557, 5574.665520068809, 5603.887922241533, 5625.803096891589, 5665.66006039639, 5668.858278543069, 5365.141577347606, 5388.326459192601, 5393.676379417169, 5393.892122157614, 5397.568194544427, 6226.377362263737, 6266.530010249249, 6267.592606480564, 6282.717277722605, 6331.430171284606, 6118.43644382056, 6122.550631082853, 6217.316095229611, 6226.968865155645, 6299.559030867796, 6268.811935641938, 6270.372962703693, 6275.905861412174, 6288.025356069817, 6304.18045654062, 5332.466753324637, 5935.931532324224, 5953.434423729739, 6123.0, 6150.323464419, 5123.3339665843205, 6137.376985335611, 6210.254769427697, 6256.436920677161, 6269.655168965751, 5967.262794824342, 6005.7297421617, 6043.214382130295, 6102.237220347457, 6110.572259941761, 5788.623739456922, 5926.644401335933, 6037.547183961308, 6056.424639659225, 6100.1459795629535, 5823.388544202967, 5833.649981001154, 5871.559633027455, 5943.7028148591535, 5945.256842894638, 5942.227510423791, 6036.154938308552, 6039.275286965592, 6046.909296360646, 6059.515238780975, 5748.281464816898, 5838.299404071538, 5843.181954526284, 5886.0, 5906.379830117776], "value": 5648.145975106984, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.3959248901318, 512.1268237654797, 518.4271380124961, 527.6622961304764, 556.9053260945639, 29589.55615665764, 30677.539836902437, 41528.792356038175, 42463.78768106163, 42471.787641061754, 43469.56530434695, 43806.0, 43825.68522944317, 43917.78041109798, 43918.0, 9687.660931867385, 9868.309218354681, 9868.555914983806, 9876.913539510648, 9910.058544438303, 8586.570671466432, 8810.163034511712, 11912.880871191308, 12084.516619335216, 12085.879141208607, 12059.939700301486, 12060.517579296818, 12108.97073748728, 12114.21257618252, 12119.212251203688, 8647.135286471366, 11859.229150105215, 11924.523019079314, 11937.224080434788, 12017.158798884082, 11633.47649355781, 11963.282203067844, 11996.220245684002, 11996.880031199664, 11998.400079995965, 41040.79479602613, 41421.0, 41529.584704152876, 41592.96017599554, 41598.58401415977, 3489.197484578561, 3504.351694936445, 3508.982455087734, 3650.233450975272, 3803.3344164771056, 31661.366772664416, 32032.0, 32239.032829015166, 32282.354352913044, 32592.0, 3278.47544392896, 3291.144302481348, 3294.390537750524, 3328.1845947742718, 3449.3963556377535, 509538.0, 509653.0, 510397.0, 510656.0, 512264.0, 61648.38351616516, 62015.0, 62092.0, 62131.16557178707, 62153.68923155355, 35243.82378088093, 35267.4709879352, 35310.47034294488, 36618.63381366152, 36682.44976325357, 16670.99974001564, 16738.414155504743, 16873.0, 16886.240119194666, 16898.239579218964, 11621.709457263636, 11828.88171118295, 12716.618501444971, 12842.422091005927, 12962.935185324013, 19456.141744795714, 19458.10116901559, 19500.27618673228, 19570.70643940342, 19663.836977932417, 7833.882491762628, 7842.294193522496, 7853.09689385728, 7867.921320786719, 8015.559144247122, 898.2634239923331, 1014.4572653630272, 1018.8318927377048, 1027.9588816447408, 1115.9330440173615, 30448.54327185094, 30604.54093188604, 30770.38459230803, 31034.0, 31043.068707938797, 934.2525979216599, 1070.2026989892424, 1077.5582011375297, 1101.9338839669645, 1163.4648061891626, 16165.0, 16166.676666466605, 16167.029978201346, 16517.091559964076, 16618.0, 697297.0, 968171.0, 990904.0, 991005.0449747705, 995139.0, 61475.0, 61831.0, 62544.56004919682, 62559.0, 62820.68589657023, 1269.60642200918, 1317.7891537353978, 1407.8240219972504, 1413.0673755321438, 1442.7980082788417, 25151.0, 30928.69071309258, 31001.379972399973, 32157.517637235927, 32276.0, 57571.0, 57992.71003644955, 58580.70709646425, 58606.0, 58708.11937820851, 656156.0, 661857.0, 662182.0, 662535.0, 663429.0485642815, 24005.0, 24327.878360608083, 24349.56504349551, 26355.4728905417, 27317.44873166555, 38436.23127537489, 39318.41022384609, 47276.7636161817, 47309.290360645304, 48740.0, 38650.42024369581, 39833.4149926508, 40064.0, 40124.19751604893, 41141.58858411497, 37094.8145259272, 37223.069423264635, 39625.207495850496, 40385.798071010635, 40919.795401022806, 37671.43492847664, 38124.0, 38634.6136538631, 38832.0, 38842.805785970886, 33364.999050028535, 34289.31421371608, 35115.82442087875, 35190.648093518736, 35297.823510882285, 20289.49276268105, 25464.108756193444, 25464.745352546237, 25849.741502584733, 28150.0], "value": 20731.156693552886, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.4548813060085, 377.9074126838924, 388.4833171881397, 389.585091877151, 410.28200648864487, 15889.119310128744, 17141.400050998218, 20679.483012924647, 21572.8921355393, 21580.136794528218, 16898.408555700553, 20578.89710551449, 20794.272200472948, 20891.686624700604, 20938.267160649342, 5749.223854779614, 5817.738201780909, 6052.485538729214, 6089.78685745999, 6092.51259899207, 5884.264466941632, 5929.495992840592, 5986.132010858423, 5991.071383935475, 6004.519638428916, 5687.943120568803, 5945.108233764927, 5950.404959504058, 5987.461128498434, 6023.72893219804, 5961.970190149043, 5998.220231369915, 6008.48927841134, 6023.126646636235, 6027.939720602782, 5048.848534543933, 5119.385673719177, 5206.739663016834, 5216.817411390621, 5221.895562088775, 24462.26613201607, 25175.118870839433, 25253.62119568208, 25565.488690226277, 25917.61123583148, 1943.6598595245775, 2275.8293128015466, 2441.1333976438286, 2496.500699860017, 3089.3821235752716, 14311.713765724731, 15301.770473442908, 15682.059076455449, 15887.767044214754, 15909.52271431859, 1610.3317123393776, 1747.8339557741995, 1802.9639407211914, 1830.752848365467, 1840.4938641873457, 115106.84893151045, 116040.0, 116114.41942790232, 117006.0, 118512.0, 10584.994425529563, 10848.945755271174, 11073.947974942368, 11082.556697732161, 11264.155188360906, 13589.0, 13660.453581856813, 13684.178949263076, 13694.246816424993, 13704.65738356549, 8436.831263374697, 8628.611712472954, 8677.869831952527, 8758.080401557872, 8775.91224087751, 8732.039475657673, 8732.991411975281, 8733.475991440408, 8863.822723545492, 8883.111688831064, 6614.966925165343, 6741.157355330583, 6749.797506074826, 6750.0, 6755.527113102035, 1615.87073034157, 1628.9266982985794, 1789.5078853315442, 1821.298799962024, 1828.542864283929, 891.5675897189858, 939.2532936315646, 952.6094301336419, 1022.8925962773801, 1026.6919924022764, 21214.46963825917, 21436.892815536135, 21539.784602153777, 21569.0, 21859.453513661967, 934.1125930366179, 962.9855552166723, 964.3731574476506, 979.5004547680734, 995.2286977592424, 9839.163671087894, 11447.348767737118, 11675.0, 11807.763844723057, 11882.702932426759, 566617.0, 569337.0, 607437.0, 629480.0, 632793.8360308261, 113811.43094284476, 117579.41210293894, 118034.40982795203, 120000.3986326907, 122602.0, 992.9404235745734, 1009.7172791618506, 1017.0947856407803, 1030.0523518363125, 1032.963846265377, 19519.414417567496, 19803.40589782309, 19877.900610497432, 19909.800901990795, 19957.101930412875, 42173.578264216965, 50632.493675062775, 53822.461775381744, 54768.45231547634, 56027.0, 53613.0, 75751.62124189564, 77081.0, 78792.0, 79085.0, 10437.582496700046, 11688.824667630162, 11885.881141188478, 12304.384780760747, 12336.444859981142, 13582.660433489242, 14066.085704429179, 14118.2940852959, 14208.78686819678, 14280.714385712436, 10766.676999690022, 11907.285562866256, 12068.0, 12159.817602736142, 12204.877951220375, 11082.279651822606, 11868.169228153947, 11881.5841445549, 11914.702132446759, 12187.390630468264, 11962.521499139937, 11987.40062996864, 12091.395430228278, 12217.633470995885, 12219.51121955112, 12276.81584776247, 12306.753864922472, 12367.443465043914, 12397.690057748629, 12494.625161245178, 11929.522819087486, 12024.519019239133, 12027.977621901886, 12207.938960305142, 12228.87771122313], "value": 10073.907924048213, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.1585824717802, 1496.1920562896037, 1551.9922400388, 1747.3098126240136, 1914.8851068935865, 7809.726659566916, 8234.83530329393, 8245.670173193064, 8248.13394593568, 8250.628721707522, 8056.355491560676, 8435.409521333493, 8686.348523860719, 8695.652173913051, 8848.734537963872, 2325.534893021394, 2337.345543247893, 2368.692070030894, 2369.9052037918464, 2371.7865392114704, 2346.2022912209845, 2363.7518060603647, 2384.153625462962, 2388.176079252655, 2394.8802559872024, 2378.5599664062206, 2381.20229723043, 2386.0217310902535, 2398.328468028955, 2398.6282126270457, 2014.9395518134406, 2367.763223677636, 2371.7746814052725, 2377.9405514862265, 2383.21353953195, 2320.8027317678016, 2353.458704497958, 2354.164271683545, 2355.6348765941307, 2357.787799098089, 8808.0, 8810.911890881072, 8875.86686199701, 8883.978342490567, 8889.377743557889, 5179.093658609729, 5211.088059589557, 5368.62419630626, 5373.543248823855, 5376.1398176291605, 3531.487934249545, 3577.9821100894587, 3659.6340365963456, 3666.156783939709, 3673.5591728992426, 1093.103655002898, 1099.312929419113, 1102.2229328323513, 1116.2465335898316, 1118.289885922437, 9779.364341317792, 11664.35846028468, 11686.590969316034, 11690.123240756891, 11693.70765730855, 4462.129884672523, 4480.372747815309, 4651.651126165551, 4654.790534425959, 4655.255159174516, 9203.35576509638, 9216.262698984065, 9225.58484868169, 9235.0, 9242.983271840092, 5239.659422137549, 5250.842474725764, 5253.605979551549, 5257.658252213594, 5266.3943646480375, 4340.435743353344, 4396.516383197845, 4529.184746745552, 4893.388326459192, 4914.483979182206, 3284.9488163787837, 3301.8844340447968, 3819.8472061117245, 3844.6347596978244, 3864.5073927317285, 1157.89578937896, 1169.1640477058943, 1173.2256710570982, 1183.3018519073787, 1185.911056670736, 830.6303694855732, 855.6662901468433, 888.4757992784288, 907.8728977943093, 957.004715096306, 6031.909521357183, 6115.571909966348, 6163.630182189083, 6182.814515564541, 6235.501159907197, 887.8046829697586, 899.5606681029142, 956.9282303827241, 957.4781743949565, 1003.7741508160751, 3481.060113769268, 3513.297340531904, 3926.2147570485445, 3970.3647416413237, 4002.4396584478195, 10444.843327350096, 10883.564657413615, 10884.0, 10897.182711296684, 10902.5093870776, 10078.14335781452, 10647.14822814173, 10820.621278255381, 10864.837027444595, 10878.728031799268, 8128.146544612849, 8177.345812334999, 8320.583970801435, 8327.084020757711, 8338.332933365316, 4944.505549445171, 5890.263717035371, 6010.87978240424, 6137.693115344304, 6200.418262472221, 9823.066808653166, 9850.458224797418, 10115.443650599287, 10182.898171018194, 10216.795664086825, 8011.3590912728005, 10227.693169204937, 10314.896851031694, 10334.379937203792, 10341.586336546454, 7603.9619801900635, 7703.345215656732, 7781.494202876795, 7795.493292935941, 7797.7660670179985, 7990.161033091675, 8301.252887240178, 8336.458130221516, 8419.242268195892, 8457.746267611981, 7397.112346518237, 7409.592472414069, 7450.739224127129, 7469.962650186714, 7657.578833164007, 7375.96312018458, 7380.5940673263485, 7469.925300746923, 7512.887306690294, 7520.962395187989, 6784.423324017514, 6872.41584465306, 6883.552569082994, 6885.758998435231, 6894.172699276119, 4673.205555055581, 4847.442544107473, 4929.359183306147, 4956.851294461172, 5064.34163558735, 4272.320741700258, 4568.520305367889, 4870.439899411613, 4930.580900623487, 5045.62157838171], "value": 4541.998942689466, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.3520930000499, 303.3720199187681, 311.7957737681818, 317.3684368107465, 319.3899651665317, 4366.0, 4374.825006999717, 5338.306020217371, 5388.326459192601, 5424.159255315428, 4401.823927042922, 4990.800367985276, 5014.724190169539, 5143.665661731985, 5293.920591191135, 3537.434010558311, 3930.8820735377985, 4054.209429161315, 4102.835886564534, 4125.381192821086, 3380.1380647934743, 3580.53453051103, 4075.7554546727292, 4112.91774164518, 4116.917661646765, 3738.7943663098517, 3859.0, 4053.797310134496, 4054.797260136996, 4088.2232375848644, 4031.455753473302, 4053.4730485036903, 4056.8782936511952, 4068.593140685937, 4091.468109145822, 3383.272596391763, 3545.4504551794257, 3546.8758593449365, 3555.2178520725656, 3582.5521809773777, 4713.670043096985, 4790.568848803624, 4996.775145118479, 5164.380274367062, 5197.5322220999915, 3636.7454278200535, 4415.984323605557, 4540.568645978659, 4540.841070562515, 4573.954260457386, 4992.101421744084, 5415.702136382497, 5523.4200408956885, 5574.60977731559, 5643.379228284885, 3554.7867127972404, 3853.84584616615, 3854.4603755473977, 3858.6913046956456, 3860.4402361657685, 5432.293801805779, 5823.56323275756, 5852.858692554953, 5854.160732044955, 5909.674967876765, 3257.42994975883, 3543.9645603543636, 3544.4151714967256, 3814.6948244140403, 3885.96114038856, 3462.688358047788, 3945.6251656092627, 3947.980260098681, 3951.881443556698, 3962.9405558916637, 3956.8920702203427, 4199.601037901394, 4350.934735978963, 4421.889452763707, 4562.5665561771575, 5361.249425080492, 5578.609497335228, 5592.7762889483965, 5606.971965140148, 5610.372991832404, 1317.5652034828483, 1325.8806707396382, 1339.9665008374868, 1351.3648585164956, 1351.6620844788804, 686.7870960002415, 826.8222332198607, 844.594594594598, 851.9275861551714, 895.4045559702724, 4039.1315867088715, 5035.777102654304, 5109.6678715883345, 5121.692698438105, 5206.583473322125, 4806.663533552688, 5634.577406694514, 5651.802186923438, 5773.509251713567, 5784.566157538202, 4223.028703398236, 4716.764161791897, 4804.0, 5077.619178561548, 5162.380514338303, 2516.8993240270183, 2582.3544113971507, 2597.3636459067648, 2990.5813186153955, 3030.196997795586, 5468.890622187613, 5682.431756824284, 5840.7583790996405, 5844.620099693591, 5852.15143804146, 5314.893702125936, 5533.253010843565, 5706.743196556165, 6009.338972712998, 6018.3680713524445, 5840.2115714378015, 5986.820395388146, 6014.0377539593455, 6030.4572588467245, 6044.848878778066, 5435.755391007493, 5546.306711661043, 5799.3040835100055, 5867.501262392744, 5881.206037185097, 5429.53848922846, 5718.399568045295, 5739.799107031401, 5752.338481074563, 5755.482006619424, 4940.92588611164, 6050.818475445743, 6075.2405949256345, 6085.1785009023215, 6092.482139018233, 3163.434100120363, 3910.941335880021, 3916.5300163979364, 3919.1769728356226, 3924.3524818405785, 5102.183650615883, 5593.860153496195, 5766.884662306814, 5837.562182836219, 5880.323762767165, 5177.974110129425, 5339.839804805862, 5446.828931779528, 5689.175069614966, 5693.402192769865, 5097.643164978416, 5454.536364409069, 5568.276124103841, 5584.0227960106, 5592.0, 5069.467705890828, 5193.480651934777, 5401.459854014567, 5513.917291240715, 5514.889702205854, 5286.841394758163, 5336.893262134813, 5603.702481982367, 5611.775528978795, 5625.0, 4539.909201815879, 5462.508374246178, 5479.315085614298, 5504.339479262514, 5546.057170280981], "value": 4061.0662898004184, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.9008513595255, 731.7768080735375, 739.8631253218155, 776.945613807033, 802.1897883138029, 13813.861861381383, 14871.776923346144, 16139.0, 16158.192090395478, 18247.726284105716, 16974.40589579365, 17033.0, 17119.828801711978, 17131.571710707238, 17201.913990430032, 717.8420747435582, 725.6698202317959, 746.238836386885, 880.4145243413122, 905.2757793764995, 878.2096113497839, 885.0883589902411, 889.3507739350279, 889.7063968890251, 889.8086911314066, 940.05994005994, 943.1982814607563, 943.7310366545536, 945.3854994253755, 945.6075728572669, 741.0070505522599, 929.5445231836419, 943.2312665177858, 964.6334392930712, 966.1884017425326, 690.9136357955256, 699.3985172751429, 700.5586480517242, 700.8878579427318, 701.9894701579481, 15379.538613841603, 15395.923020384938, 15409.845901541064, 15411.3835446582, 15427.691446171013, 14929.626759331104, 14934.25328733559, 15055.322510487054, 15057.924710376377, 15136.470223542123, 10413.895861041368, 11975.760484790342, 12101.818472722916, 12233.510659573607, 12555.246685198917, 3825.827837747308, 3947.210557888434, 3990.521137463523, 4048.251073551373, 4075.001624601962, 86790.0, 106398.93601063968, 111059.33410998761, 112480.0, 112552.43723781328, 11445.599404020819, 11467.655970320904, 11489.195756297146, 11530.827037594443, 12118.878811211951, 24113.397165071012, 24131.758682413303, 24132.396690082536, 24160.516789664107, 24165.879170604036, 8180.754577362689, 8236.176382361835, 8438.620262088221, 8471.152884711602, 8473.279771219515, 4425.402570652985, 4562.840300589463, 4582.587567118975, 4600.079984003212, 4724.385829842167, 3683.4843121962945, 3830.5020347354666, 3835.6931445484292, 3839.212961342918, 3845.8846234613006, 623.4357906094991, 661.7650733989365, 668.8394785251504, 676.7597502886503, 692.705600119947, 567.625367257608, 568.457123447107, 575.8790653962715, 682.1984168601873, 696.6621188723465, 9016.59425325862, 9303.162715355651, 9493.525323733924, 9515.191208747196, 9642.469664168539, 552.7264004317889, 593.7535922592076, 603.7705671844759, 686.3691074217434, 697.9406750426169, 4712.835050773207, 4718.693284936468, 4728.40894888139, 4729.787159577827, 4771.880702982384, 87324.0, 120512.19231711532, 127399.0, 131726.0, 155721.0, 109569.0, 129504.0, 129644.98017668603, 131038.34480827725, 132441.0, 111015.0, 114540.42729786297, 115593.0, 116368.99999999831, 116402.41798790952, 9000.954995224982, 9034.27725781949, 9059.22999027055, 9099.317551183556, 9181.265498760216, 49179.754101229264, 53299.20051199313, 61266.0, 62247.6887615559, 64297.67851160714, 90921.0, 95222.57166142653, 95367.52316238375, 95549.0, 95963.56054659047, 10276.845847312446, 10627.25609207379, 12053.457594408448, 12066.818997715216, 12883.871161288267, 12654.493820247455, 13152.934235328763, 13506.72986540244, 13522.323883805968, 13584.93207533956, 11770.704712580964, 12202.705269577342, 12526.561570344995, 12584.119111662096, 12999.805002924775, 12044.494438195226, 12092.637220883387, 12171.33057681836, 12323.0, 12898.290594017419, 10610.840837387292, 12198.207116537396, 12231.816522751988, 12471.625851224479, 12613.369331533204, 10530.21023423231, 10757.085647720032, 10844.0, 10908.890911090788, 11112.333260004425, 8508.787280318042, 10373.792524149625, 10846.29499082557, 10916.454177290945, 10925.235233533576], "value": 7798.7932627111195, "unit": "score"}], "name": "dromaeo_css", "value": 7440.723276308317, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 113.0, 113.0, 111.0, 110.0, 113.0, 112.0, 111.0, 113.0, 117.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 109.0, 107.0, 162.0, 107.0, 106.0, 128.0, 104.0, 159.0, 104.0], "value": 122.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 188.0, 183.0, 195.0, 266.0, 184.0, 180.0, 185.0, 183.0, 183.0], "value": 193.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 61.0, 133.0, 79.0, 80.0, 62.0, 59.0, 118.0, 108.0], "value": 85.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 92.0, 91.0, 72.0, 70.0, 67.0, 98.0, 69.0, 68.0, 100.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 86.0, 87.0, 84.0, 88.0, 85.0, 87.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 94.0, 109.0, 149.0, 150.0, 94.0, 95.0, 93.0, 95.0], "value": 106.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 107.0, 139.0, 86.0, 127.0, 141.0, 119.0, 84.0], "value": 105.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 55.0, 53.0, 54.0, 55.0, 56.0, 54.0, 53.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 74.0, 80.0, 72.0, 68.0, 69.0, 69.0, 69.0, 71.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 83.0, 85.0, 81.0, 84.0, 83.0, 83.0, 85.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 136.0, 134.0, 132.0, 138.0, 136.0, 137.0, 225.0, 136.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 163.0, 168.0, 220.0, 219.0, 164.0, 172.0, 226.0, 194.0], "value": 185.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 66.0, 66.0, 67.0, 67.0, 66.0, 66.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1497.1, "alertThreshold": 2.0}]} 09:25:08 INFO - Return code: 0 09:25:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:25:08 INFO - # TBPL SUCCESS # 09:25:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:25:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:25:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:25:08 INFO - 2016-07-07 09:25:08.550 screenresolution[2239:30792] starting screenresolution argv=screenresolution get 09:25:08 INFO - 2016-07-07 09:25:08.553 screenresolution[2239:30792] Display 0: 1600x1200x32@60 09:25:08 INFO - 2016-07-07 09:25:08.559 screenresolution[2240:30795] starting screenresolution argv=screenresolution list 09:25:08 INFO - Available Modes on Display 0 09:25:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:25:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:25:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:25:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:25:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:25:08 INFO - Intel Iris: 09:25:08 INFO - Chipset Model: Intel Iris 09:25:08 INFO - Type: GPU 09:25:08 INFO - Bus: Built-In 09:25:08 INFO - VRAM (Dynamic, Max): 1536 MB 09:25:08 INFO - Vendor: Intel (0x8086) 09:25:08 INFO - Device ID: 0x0a2e 09:25:08 INFO - Revision ID: 0x0009 09:25:08 INFO - Displays: 09:25:08 INFO - Display: 09:25:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:25:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:25:08 INFO - Display Serial Number: 1600x1200 60 09:25:08 INFO - Main Display: Yes 09:25:08 INFO - Mirror: Off 09:25:08 INFO - Online: Yes 09:25:08 INFO - Rotation: Supported 09:25:08 INFO - Return code: 0 09:25:08 INFO - Running post-action listener: _resource_record_post_action 09:25:08 INFO - ##### 09:25:08 INFO - ##### Finished run-tests step (success) 09:25:08 INFO - ##### 09:25:08 INFO - Running post-run listener: _resource_record_post_run 09:25:08 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 278528; Write bytes: 706078720; Read time: 3; Write time: 1852 09:25:08 INFO - TinderboxPrint: CPU usage
53.3% 09:25:08 INFO - TinderboxPrint: I/O read bytes / time
278,528 / 3 09:25:08 INFO - TinderboxPrint: I/O write bytes / time
706,078,720 / 1,852 09:25:08 INFO - TinderboxPrint: CPU idle
2,263.6 (46.7%) 09:25:08 INFO - TinderboxPrint: CPU system
965.5 (19.9%) 09:25:08 INFO - TinderboxPrint: CPU user
1,616.6 (33.4%) 09:25:08 INFO - TinderboxPrint: Swap in / out
406,671,360 / 0 09:25:08 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402727424; Write bytes: 394933760; Read time: 29548; Write time: 723 09:25:08 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 241664; Write bytes: 294112256; Read time: 3; Write time: 1083 09:25:09 INFO - Running post-run listener: _upload_blobber_files 09:25:09 INFO - Blob upload gear active. 09:25:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:25:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:25:09 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'] 09:25:09 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 09:25:09 INFO - (blobuploader) - INFO - Open directory for files ... 09:25:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:25:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:25:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:25:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:25:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:25:10 INFO - (blobuploader) - INFO - Done attempting. 09:25:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:25:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:25:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:25:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:25:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:25:11 INFO - (blobuploader) - INFO - Done attempting. 09:25:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:25:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:25:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:25:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:25:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:25:14 INFO - (blobuploader) - INFO - Done attempting. 09:25:14 INFO - (blobuploader) - INFO - Iteration through files over. 09:25:14 INFO - Return code: 0 09:25:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:25:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:25:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d5fbb768e5b6ac610adb57702e16134d13c1b5e82b1c3aa2759a65aa7a254fdffb30bd5e1ad774ad744a581fdddb389f9a9bf6c7c3ae910a286fe51c6594e5f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/23bb3df7e1db71442cb8347b3af574f591c74e8d80e364d9c585fa18de5dd231120751145507c4daf1895e3fcdc253f82374f0672d8401ed206c1cd8f2c836a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6d2abfb46f5bda4b82316e89630195fa6b342aa67dd3aca436057823946c5e555e60e378a52a9f2b7b25955cfe9b665ae62841551c47efc277b76721159601a2"} 09:25:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:25:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:25:14 INFO - Contents: 09:25:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d5fbb768e5b6ac610adb57702e16134d13c1b5e82b1c3aa2759a65aa7a254fdffb30bd5e1ad774ad744a581fdddb389f9a9bf6c7c3ae910a286fe51c6594e5f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/23bb3df7e1db71442cb8347b3af574f591c74e8d80e364d9c585fa18de5dd231120751145507c4daf1895e3fcdc253f82374f0672d8401ed206c1cd8f2c836a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6d2abfb46f5bda4b82316e89630195fa6b342aa67dd3aca436057823946c5e555e60e378a52a9f2b7b25955cfe9b665ae62841551c47efc277b76721159601a2"} 09:25:14 INFO - Running post-run listener: copy_logs_to_upload_dir 09:25:14 INFO - Copying logs to upload dir... 09:25:14 INFO - mkdir: /builds/slave/test/build/upload/logs 09:25:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.070355 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-07-07 09:25:14.524212) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 09:25:14.528112) ========= 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.mCMUmqEzjd/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.wyrXBhpMMm/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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/1d5fbb768e5b6ac610adb57702e16134d13c1b5e82b1c3aa2759a65aa7a254fdffb30bd5e1ad774ad744a581fdddb389f9a9bf6c7c3ae910a286fe51c6594e5f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/23bb3df7e1db71442cb8347b3af574f591c74e8d80e364d9c585fa18de5dd231120751145507c4daf1895e3fcdc253f82374f0672d8401ed206c1cd8f2c836a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6d2abfb46f5bda4b82316e89630195fa6b342aa67dd3aca436057823946c5e555e60e378a52a9f2b7b25955cfe9b665ae62841551c47efc277b76721159601a2"} build_url:https://queue.taskcluster.net/v1/task/JAYmum6_TRixFa1xGG06IA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011335 build_url: 'https://queue.taskcluster.net/v1/task/JAYmum6_TRixFa1xGG06IA/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/1d5fbb768e5b6ac610adb57702e16134d13c1b5e82b1c3aa2759a65aa7a254fdffb30bd5e1ad774ad744a581fdddb389f9a9bf6c7c3ae910a286fe51c6594e5f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/23bb3df7e1db71442cb8347b3af574f591c74e8d80e364d9c585fa18de5dd231120751145507c4daf1895e3fcdc253f82374f0672d8401ed206c1cd8f2c836a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6d2abfb46f5bda4b82316e89630195fa6b342aa67dd3aca436057823946c5e555e60e378a52a9f2b7b25955cfe9b665ae62841551c47efc277b76721159601a2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 09:25:14.560868) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 09:25:14.561179) ========= 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.mCMUmqEzjd/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.wyrXBhpMMm/Listeners TMPDIR=/var/folders/0w/_9q7s0fs5jx2xng3q6l6pqxw00000w/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.004869 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 09:25:14.609369) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 09:25:14.609665) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 09:25:14.609982) ========= ========= Total master_lag: 0.18 =========