builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0312 starttime: 1469167920.62 results: success (0) buildid: 20160721221515 builduid: a6642a3803994658afcc475977fca1a9 revision: 2ce848f8b804c55792014cd9b70c9a54854bf9d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 23:12:00.616189) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 23:12:00.616616) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 23:12:00.616899) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 23:12:00.671032) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 23:12:00.671405) ========= 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.q5FFcZ3xha/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.P0nSKspjUY/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-21 23:12:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 152M=0s 2016-07-21 23:12:01 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.551091 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 23:12:01.244578) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 23:12:01.244868) ========= 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.q5FFcZ3xha/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.P0nSKspjUY/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.020640 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 23:12:01.298476) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 23:12:01.298784) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 2ce848f8b804c55792014cd9b70c9a54854bf9d7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 2ce848f8b804c55792014cd9b70c9a54854bf9d7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.q5FFcZ3xha/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.P0nSKspjUY/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-21 23:12:01,380 truncating revision to first 12 chars 2016-07-21 23:12:01,380 Setting DEBUG logging. 2016-07-21 23:12:01,380 attempt 1/10 2016-07-21 23:12:01,380 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/2ce848f8b804?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 23:12:02,094 unpacking tar archive at: autoland-2ce848f8b804/testing/mozharness/ program finished with exit code 0 elapsedTime=1.004042 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 23:12:02.327378) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 23:12:02.327857) ========= script_repo_revision: 2ce848f8b804c55792014cd9b70c9a54854bf9d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 23:12:02.328292) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 23:12:02.328557) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 23:12:02.351765) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 7 secs) (at 2016-07-21 23:12:02.352339) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.q5FFcZ3xha/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.P0nSKspjUY/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 23:12:02 INFO - MultiFileLogger online at 20160721 23:12:02 in /builds/slave/test 23:12:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 23:12:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:12:02 INFO - {'append_to_log': False, 23:12:02 INFO - 'base_work_dir': '/builds/slave/test', 23:12:02 INFO - 'blob_upload_branch': 'Autoland', 23:12:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:12:02 INFO - 'branch': 'Autoland', 23:12:02 INFO - 'buildbot_json_path': 'buildprops.json', 23:12:02 INFO - 'config_files': ('talos/mac_config.py',), 23:12:02 INFO - 'default_actions': ('clobber', 23:12:02 INFO - 'read-buildbot-config', 23:12:02 INFO - 'download-and-extract', 23:12:02 INFO - 'populate-webroot', 23:12:02 INFO - 'create-virtualenv', 23:12:02 INFO - 'install', 23:12:02 INFO - 'run-tests'), 23:12:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:12:02 INFO - 'download_minidump_stackwalk': True, 23:12:02 INFO - 'download_symbols': 'ondemand', 23:12:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:12:02 INFO - 'tooltool.py': '/tools/tooltool.py', 23:12:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:12:02 INFO - '/tools/misc-python/virtualenv.py')}, 23:12:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:12:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:12:02 INFO - 'installer_path': 'installer.exe', 23:12:02 INFO - 'log_level': 'info', 23:12:02 INFO - 'log_name': 'talos', 23:12:02 INFO - 'log_to_console': True, 23:12:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:12:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:12:02 INFO - 'opt_config_files': (), 23:12:02 INFO - 'pip_index': False, 23:12:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:12:02 INFO - 'cmd': ('bash', 23:12:02 INFO - '-c', 23:12:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:12:02 INFO - 'enabled': True, 23:12:02 INFO - 'halt_on_failure': False, 23:12:02 INFO - 'name': 'check_screen_resolution'},), 23:12:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:12:02 INFO - 'cmd': ('bash', 23:12:02 INFO - '-c', 23:12:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:12:02 INFO - 'enabled': True, 23:12:02 INFO - 'halt_on_failure': False, 23:12:02 INFO - 'name': 'check_screen_resolution'},), 23:12:02 INFO - 'run_cmd_checks_enabled': True, 23:12:02 INFO - 'sps_profile': False, 23:12:02 INFO - 'sps_profile_interval': 0, 23:12:02 INFO - 'suite': 'dromaeojs', 23:12:02 INFO - 'system_bits': '32', 23:12:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:12:02 INFO - 'title': 't-yosemite-r7-0312', 23:12:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:12:02 INFO - 'use_talos_json': True, 23:12:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:12:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:12:02 INFO - 'work_dir': 'build'} 23:12:02 INFO - [mozharness: 2016-07-22 06:12:02.541101Z] Running clobber step. 23:12:02 INFO - Running pre-action listener: _resource_record_pre_action 23:12:02 INFO - Running main action method: clobber 23:12:02 INFO - rmtree: /builds/slave/test/build 23:12:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:12:03 INFO - Running post-action listener: _resource_record_post_action 23:12:03 INFO - [mozharness: 2016-07-22 06:12:03.908188Z] Finished clobber step (success) 23:12:03 INFO - [mozharness: 2016-07-22 06:12:03.908312Z] Running read-buildbot-config step. 23:12:03 INFO - Running pre-action listener: _resource_record_pre_action 23:12:03 INFO - Running main action method: read_buildbot_config 23:12:03 INFO - Using buildbot properties: 23:12:03 INFO - { 23:12:03 INFO - "project": "", 23:12:03 INFO - "product": "firefox", 23:12:03 INFO - "script_repo_revision": "production", 23:12:03 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 23:12:03 INFO - "repository": "", 23:12:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 23:12:03 INFO - "buildid": "20160721221515", 23:12:03 INFO - "pgo_build": "False", 23:12:03 INFO - "basedir": "/builds/slave/test", 23:12:03 INFO - "buildnumber": 27, 23:12:03 INFO - "slavename": "t-yosemite-r7-0312", 23:12:03 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 23:12:03 INFO - "platform": "yosemite_r7", 23:12:03 INFO - "scheduler": "tests-autoland-macosx64-talos", 23:12:03 INFO - "branch": "autoland", 23:12:03 INFO - "repo_path": "integration/autoland", 23:12:03 INFO - "revision": "2ce848f8b804c55792014cd9b70c9a54854bf9d7", 23:12:03 INFO - "stage_platform": "macosx64", 23:12:03 INFO - "builduid": "a6642a3803994658afcc475977fca1a9", 23:12:03 INFO - "slavebuilddir": "test" 23:12:03 INFO - } 23:12:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 23:12:03 INFO - Running post-action listener: _resource_record_post_action 23:12:03 INFO - [mozharness: 2016-07-22 06:12:03.911643Z] Finished read-buildbot-config step (success) 23:12:03 INFO - [mozharness: 2016-07-22 06:12:03.911744Z] Running download-and-extract step. 23:12:03 INFO - Running pre-action listener: _resource_record_pre_action 23:12:03 INFO - Running main action method: download_and_extract 23:12:03 INFO - mkdir: /builds/slave/test/build/tests 23:12: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')]} 23:12:03 INFO - https://queue.taskcluster.net/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 23:12:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 23:12:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 23:12:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/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 23:12:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/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 23:12:13 INFO - Downloaded 1199 bytes. 23:12:13 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 23:12:13 INFO - Using the following test package requirements: 23:12:13 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 23:12:13 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:12:13 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 23:12:13 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:12:13 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 23:12:13 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:12:13 INFO - u'jsshell-mac.zip'], 23:12:13 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:12:13 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 23:12:13 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 23:12:13 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:12:13 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 23:12:13 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:12:13 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 23:12:13 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:12:13 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 23:12:13 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:12:13 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 23:12:13 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:13 INFO - https://queue.taskcluster.net/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:12:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:12:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:12:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/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 23:12:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/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 23:12:15 INFO - Downloaded 18806763 bytes. 23:12:15 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'] 23:12:15 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 23:12:18 INFO - Return code: 0 23:12:18 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 23:12: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')]} 23:12:18 INFO - https://queue.taskcluster.net/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:12:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:12:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:12:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/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 23:12:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/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 23:12:28 INFO - retry: attempt #1 caught exception: '' 23:12:28 INFO - retry: Failed, sleeping 30 seconds before retrying 23:12:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/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 #2 23:13:09 INFO - Downloaded 18806763 bytes. 23:13:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:13:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 23:13:13 INFO - Return code: 0 23:13:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:13 INFO - https://queue.taskcluster.net/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 23:13:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 23:13:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 23:13:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/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 23:13:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/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 23:13:15 INFO - Downloaded 14403289 bytes. 23:13:15 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'] 23:13:15 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 23:13:15 INFO - Return code: 0 23:13:15 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')]} 23:13:15 INFO - https://queue.taskcluster.net/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 23:13:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 23:13:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 23:13:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 23:13:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aSRfr_KqRFulWQSsoN08AQ/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 23:13:18 INFO - Downloaded 112790353 bytes. 23:13:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 23:13:18 INFO - mkdir: /builds/slave/test/properties 23:13:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:13:18 INFO - Writing to file /builds/slave/test/properties/build_url 23:13:18 INFO - Contents: 23:13:18 INFO - build_url:https://queue.taskcluster.net/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 23:13:20 INFO - Running post-action listener: _resource_record_post_action 23:13:20 INFO - Running post-action listener: set_extra_try_arguments 23:13:20 INFO - [mozharness: 2016-07-22 06:13:20.185680Z] Finished download-and-extract step (success) 23:13:20 INFO - [mozharness: 2016-07-22 06:13:20.185843Z] Running populate-webroot step. 23:13:20 INFO - Running pre-action listener: _resource_record_pre_action 23:13:20 INFO - Running main action method: populate_webroot 23:13:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:13:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:13:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:13:20 INFO - '--tppagecycles', 23:13:20 INFO - '7'], 23:13:20 INFO - 'tests': ['tsvgm']}}, 23:13:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:13:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:13:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:13:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:13:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:13:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:13:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:13:20 INFO - 'talos_options': ['--disable-e10s'], 23:13:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:13:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:13:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:13:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:13:20 INFO - 'talos_options': ['--disable-e10s'], 23:13:20 INFO - 'tests': ['damp', 'tps']}, 23:13:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:13:20 INFO - 'tests': ['damp', 'tps']}, 23:13:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:13:20 INFO - 'tests': ['dromaeo_dom']}, 23:13:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:13:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:13:20 INFO - 'tests': ['basic_compositor_video']}, 23:13:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:13:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:13:20 INFO - 'tests': ['a11yr', 23:13:20 INFO - 'ts_paint', 23:13:20 INFO - 'tpaint', 23:13:20 INFO - 'sessionrestore', 23:13:20 INFO - 'sessionrestore_no_auto_restore', 23:13:20 INFO - 'tabpaint']}, 23:13:20 INFO - 'other-e10s': {'tests': ['a11yr', 23:13:20 INFO - 'ts_paint', 23:13:20 INFO - 'tpaint', 23:13:20 INFO - 'sessionrestore', 23:13:20 INFO - 'sessionrestore_no_auto_restore', 23:13:20 INFO - 'tabpaint']}, 23:13:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:13:20 INFO - 'ts_paint', 23:13:20 INFO - 'tpaint', 23:13:20 INFO - 'sessionrestore', 23:13:20 INFO - 'sessionrestore_no_auto_restore', 23:13:20 INFO - 'tabpaint']}, 23:13:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:13:20 INFO - 'ts_paint', 23:13:20 INFO - 'tpaint', 23:13:20 INFO - 'sessionrestore', 23:13:20 INFO - 'sessionrestore_no_auto_restore', 23:13:20 INFO - 'tabpaint']}, 23:13:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:13:20 INFO - 'tests': ['a11yr', 23:13:20 INFO - 'ts_paint', 23:13:20 INFO - 'tpaint', 23:13:20 INFO - 'sessionrestore', 23:13:20 INFO - 'sessionrestore_no_auto_restore', 23:13:20 INFO - 'tabpaint']}, 23:13:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:13:20 INFO - 'tests': ['a11yr', 23:13:20 INFO - 'ts_paint', 23:13:20 INFO - 'tpaint', 23:13:20 INFO - 'sessionrestore', 23:13:20 INFO - 'sessionrestore_no_auto_restore', 23:13:20 INFO - 'tabpaint']}, 23:13:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:13:20 INFO - 'tests': ['tsvgx', 23:13:20 INFO - 'tsvgr_opacity', 23:13:20 INFO - 'tart', 23:13:20 INFO - 'tscrollx', 23:13:20 INFO - 'cart']}, 23:13:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:13:20 INFO - 'tsvgr_opacity', 23:13:20 INFO - 'tart', 23:13:20 INFO - 'tscrollx', 23:13:20 INFO - 'cart']}, 23:13:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:13:20 INFO - 'talos_options': ['--disable-e10s'], 23:13:20 INFO - 'tests': ['tp5o']}, 23:13:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:13:20 INFO - 'tests': ['tp5o']}, 23:13:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:13:20 INFO - 'talos_options': ['--disable-e10s', 23:13:20 INFO - '--xperf_path', 23:13:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:13:20 INFO - 'tests': ['tp5n']}, 23:13:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:13:20 INFO - 'talos_options': ['--xperf_path', 23:13:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:13:20 INFO - 'tests': ['tp5n']}}, 23:13:20 INFO - 'talos.zip': {'path': '', 23:13:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:13:20 INFO - Running post-action listener: _resource_record_post_action 23:13:20 INFO - [mozharness: 2016-07-22 06:13:20.204560Z] Finished populate-webroot step (success) 23:13:20 INFO - [mozharness: 2016-07-22 06:13:20.204672Z] Running create-virtualenv step. 23:13:20 INFO - Running pre-action listener: _resource_record_pre_action 23:13:20 INFO - Running main action method: create_virtualenv 23:13:20 INFO - Creating virtualenv /builds/slave/test/build/venv 23:13:20 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 23:13:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:13:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:13:20 INFO - Using real prefix '/tools/python27' 23:13:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:13:21 INFO - Installing distribute.............................................................................................................................................................................................done. 23:13:23 INFO - Installing pip.................done. 23:13:23 INFO - Return code: 0 23:13:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:13:23 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')]} 23:13:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:23 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')]} 23:13:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:23 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 0x109487a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10951c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85f2575460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ebd30>, '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 0x109150db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093e3b20>, '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.q5FFcZ3xha/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.P0nSKspjUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 23:13:23 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 23:13:23 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 23:13:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q5FFcZ3xha/Render', 23:13:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:13:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:13:23 INFO - 'HOME': '/Users/cltbld', 23:13:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:13:23 INFO - 'LOGNAME': 'cltbld', 23:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:23 INFO - 'MOZ_NO_REMOTE': '1', 23:13:23 INFO - 'NO_EM_RESTART': '1', 23:13:23 INFO - 'PAGER': '/bin/cat', 23:13:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:23 INFO - 'PWD': '/builds/slave/test', 23:13:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:13:23 INFO - 'SHELL': '/bin/bash', 23:13:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0nSKspjUY/Listeners', 23:13:23 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 23:13:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:13:23 INFO - 'USER': 'cltbld', 23:13:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:13:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:23 INFO - 'XPC_FLAGS': '0x0', 23:13:23 INFO - 'XPC_SERVICE_NAME': '0', 23:13:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:13:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:24 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 23:13:24 INFO - Cleaning up... 23:13:24 INFO - Return code: 0 23:13:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109487a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10951c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85f2575460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ebd30>, '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 0x109150db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093e3b20>, '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.q5FFcZ3xha/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.P0nSKspjUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 23:13:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:13:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:13:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q5FFcZ3xha/Render', 23:13:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:13:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:13:24 INFO - 'HOME': '/Users/cltbld', 23:13:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:13:24 INFO - 'LOGNAME': 'cltbld', 23:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:24 INFO - 'MOZ_NO_REMOTE': '1', 23:13:24 INFO - 'NO_EM_RESTART': '1', 23:13:24 INFO - 'PAGER': '/bin/cat', 23:13:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:13:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:24 INFO - 'PWD': '/builds/slave/test', 23:13:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:13:24 INFO - 'SHELL': '/bin/bash', 23:13:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0nSKspjUY/Listeners', 23:13:24 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 23:13:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:13:24 INFO - 'USER': 'cltbld', 23:13:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:13:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:24 INFO - 'XPC_FLAGS': '0x0', 23:13:24 INFO - 'XPC_SERVICE_NAME': '0', 23:13:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:13:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:24 INFO - Downloading/unpacking psutil>=3.1.1 23:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:13:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:13:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:13:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:13:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:13:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:13:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:13:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:13:26 INFO - Installing collected packages: psutil 23:13:26 INFO - Running setup.py install for psutil 23:13:27 INFO - building 'psutil._psutil_osx' extension 23:13:27 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 23:13:27 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 23:13:27 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 23:13:27 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 23:13:27 INFO - building 'psutil._psutil_posix' extension 23:13:27 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 23:13:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:13:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:13:27 INFO - ^ 23:13:27 INFO - 1 warning generated. 23:13:27 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 23:13:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:13:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:13:27 INFO - Successfully installed psutil 23:13:27 INFO - Cleaning up... 23:13:27 INFO - Return code: 0 23:13:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:27 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 0x109487a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10951c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85f2575460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ebd30>, '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 0x109150db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093e3b20>, '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.q5FFcZ3xha/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.P0nSKspjUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 23:13:27 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 23:13:27 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 23:13:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q5FFcZ3xha/Render', 23:13:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:13:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:13:27 INFO - 'HOME': '/Users/cltbld', 23:13:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:13:27 INFO - 'LOGNAME': 'cltbld', 23:13:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:27 INFO - 'MOZ_NO_REMOTE': '1', 23:13:27 INFO - 'NO_EM_RESTART': '1', 23:13:27 INFO - 'PAGER': '/bin/cat', 23:13:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:13:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:27 INFO - 'PWD': '/builds/slave/test', 23:13:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:13:27 INFO - 'SHELL': '/bin/bash', 23:13:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0nSKspjUY/Listeners', 23:13:27 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 23:13:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:13:27 INFO - 'USER': 'cltbld', 23:13:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:13:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:13:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:27 INFO - 'XPC_FLAGS': '0x0', 23:13:27 INFO - 'XPC_SERVICE_NAME': '0', 23:13:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:13:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:13:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:13:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:13:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:13:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:13:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:13:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:13:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:13:30 INFO - Installing collected packages: mozsystemmonitor 23:13:30 INFO - Running setup.py install for mozsystemmonitor 23:13:30 INFO - Successfully installed mozsystemmonitor 23:13:30 INFO - Cleaning up... 23:13:30 INFO - Return code: 0 23:13:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:13: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')]} 23:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13: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')]} 23:13:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13: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', '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 0x109487a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10951c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85f2575460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ebd30>, '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 0x109150db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093e3b20>, '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.q5FFcZ3xha/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.P0nSKspjUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 23:13: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:13: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 jsonschema==2.5.1 23:13:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q5FFcZ3xha/Render', 23:13:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:13:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:13:30 INFO - 'HOME': '/Users/cltbld', 23:13:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:13:30 INFO - 'LOGNAME': 'cltbld', 23:13:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:30 INFO - 'MOZ_NO_REMOTE': '1', 23:13:30 INFO - 'NO_EM_RESTART': '1', 23:13:30 INFO - 'PAGER': '/bin/cat', 23:13:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:13:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:30 INFO - 'PWD': '/builds/slave/test', 23:13:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:13:30 INFO - 'SHELL': '/bin/bash', 23:13:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0nSKspjUY/Listeners', 23:13:30 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 23:13:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:13:30 INFO - 'USER': 'cltbld', 23:13:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:13:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:13:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:30 INFO - 'XPC_FLAGS': '0x0', 23:13:30 INFO - 'XPC_SERVICE_NAME': '0', 23:13:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:13:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:31 INFO - Downloading/unpacking jsonschema==2.5.1 23:13:31 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 23:13:31 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 23:13:31 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 23:13:31 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 23:13:31 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 23:13:31 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 23:13:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:13:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:13:33 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 23:13:33 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 23:13:33 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 23:13:33 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 23:13:33 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 23:13:33 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 23:13:34 INFO - Downloading functools32-3.2.3-2.tar.gz 23:13:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:13:34 INFO - warning: no files found matching '*.txt' 23:13:34 INFO - no previously-included directories found matching 'build' 23:13:34 INFO - no previously-included directories found matching 'dist' 23:13:34 INFO - no previously-included directories found matching '.git*' 23:13:34 INFO - Installing collected packages: jsonschema, functools32 23:13:34 INFO - Running setup.py install for functools32 23:13:34 INFO - warning: no files found matching '*.txt' 23:13:34 INFO - no previously-included directories found matching 'build' 23:13:34 INFO - no previously-included directories found matching 'dist' 23:13:34 INFO - no previously-included directories found matching '.git*' 23:13:34 INFO - Successfully installed jsonschema functools32 23:13:34 INFO - Cleaning up... 23:13:34 INFO - Return code: 0 23:13:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:13:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:34 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 0x109487a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10951c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85f2575460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ebd30>, '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 0x109150db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093e3b20>, '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.q5FFcZ3xha/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.P0nSKspjUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 23:13:34 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 23:13:34 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 23:13:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q5FFcZ3xha/Render', 23:13:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:13:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:13:34 INFO - 'HOME': '/Users/cltbld', 23:13:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:13:34 INFO - 'LOGNAME': 'cltbld', 23:13:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:34 INFO - 'MOZ_NO_REMOTE': '1', 23:13:34 INFO - 'NO_EM_RESTART': '1', 23:13:34 INFO - 'PAGER': '/bin/cat', 23:13:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:13:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:34 INFO - 'PWD': '/builds/slave/test', 23:13:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:13:34 INFO - 'SHELL': '/bin/bash', 23:13:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0nSKspjUY/Listeners', 23:13:34 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 23:13:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:13:34 INFO - 'USER': 'cltbld', 23:13:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:13:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:13:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:34 INFO - 'XPC_FLAGS': '0x0', 23:13:34 INFO - 'XPC_SERVICE_NAME': '0', 23:13:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:13:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:34 INFO - Downloading/unpacking blobuploader==1.2.4 23:13:34 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 23:13:34 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 23:13:34 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 23:13:34 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 23:13:34 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 23:13:34 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 23:13:37 INFO - Downloading blobuploader-1.2.4.tar.gz 23:13:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:13:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:13:37 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 23:13:37 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 23:13:37 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 23:13:37 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 23:13:37 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 23:13:37 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 23:13:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:13:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:13:37 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 23:13:37 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 23:13:37 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 23:13:37 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 23:13:37 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 23:13:37 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 23:13:38 INFO - Downloading docopt-0.6.1.tar.gz 23:13:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:13:38 INFO - Installing collected packages: blobuploader, requests, docopt 23:13:38 INFO - Running setup.py install for blobuploader 23:13:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:13:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:13:38 INFO - Running setup.py install for requests 23:13:39 INFO - Running setup.py install for docopt 23:13:39 INFO - Successfully installed blobuploader requests docopt 23:13:39 INFO - Cleaning up... 23:13:39 INFO - Return code: 0 23:13:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:39 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 0x109487a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10951c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85f2575460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ebd30>, '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 0x109150db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093e3b20>, '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.q5FFcZ3xha/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.P0nSKspjUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 23:13:39 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 23:13:39 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 23:13:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q5FFcZ3xha/Render', 23:13:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:13:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:13:39 INFO - 'HOME': '/Users/cltbld', 23:13:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:13:39 INFO - 'LOGNAME': 'cltbld', 23:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:39 INFO - 'MOZ_NO_REMOTE': '1', 23:13:39 INFO - 'NO_EM_RESTART': '1', 23:13:39 INFO - 'PAGER': '/bin/cat', 23:13:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:39 INFO - 'PWD': '/builds/slave/test', 23:13:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:13:39 INFO - 'SHELL': '/bin/bash', 23:13:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0nSKspjUY/Listeners', 23:13:39 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 23:13:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:13:39 INFO - 'USER': 'cltbld', 23:13:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:13:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:39 INFO - 'XPC_FLAGS': '0x0', 23:13:39 INFO - 'XPC_SERVICE_NAME': '0', 23:13:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:13:39 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-nwTWu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:13:39 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Xe4BTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:13:39 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-MtQiBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:13:39 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-d4ECC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:13:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:13:39 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-VIpgIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:13:40 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-JawvzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:13:40 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Pc0oya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:13:40 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-nnvfY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:13:40 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-9cZYkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:13:40 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-S6zYNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:13:40 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-xqN9d5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:13:40 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-loA2QC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:13:40 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-VOBkVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:13:40 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Op_4Xl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:13:41 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-zAog4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:13:41 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-TRP5Sx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:13:41 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-WkBCx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:13:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:13:41 INFO - Running setup.py install for manifestparser 23:13:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:13:41 INFO - Running setup.py install for mozcrash 23:13:41 INFO - Running setup.py install for mozdebug 23:13:41 INFO - Running setup.py install for mozdevice 23:13:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:13:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:13:41 INFO - Running setup.py install for mozfile 23:13:42 INFO - Running setup.py install for mozhttpd 23:13:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:13:42 INFO - Running setup.py install for mozinfo 23:13:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:13:42 INFO - Running setup.py install for mozInstall 23:13:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:13:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:13:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:13:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:13:42 INFO - Running setup.py install for mozleak 23:13:42 INFO - Running setup.py install for mozlog 23:13:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:13:42 INFO - Running setup.py install for moznetwork 23:13:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:13:42 INFO - Running setup.py install for mozprocess 23:13:43 INFO - Running setup.py install for mozprofile 23:13:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:13:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:13:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:13:43 INFO - Running setup.py install for mozrunner 23:13:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:13:43 INFO - Running setup.py install for mozscreenshot 23:13:43 INFO - Running setup.py install for moztest 23:13:43 INFO - Running setup.py install for mozversion 23:13:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:13:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:13:43 INFO - Cleaning up... 23:13:43 INFO - Return code: 0 23:13:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:43 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 0x109487a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10951c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85f2575460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ebd30>, '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 0x109150db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093e3b20>, '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.q5FFcZ3xha/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.P0nSKspjUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 23:13:43 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 23:13:43 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 23:13:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q5FFcZ3xha/Render', 23:13:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:13:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:13:43 INFO - 'HOME': '/Users/cltbld', 23:13:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:13:43 INFO - 'LOGNAME': 'cltbld', 23:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:43 INFO - 'MOZ_NO_REMOTE': '1', 23:13:43 INFO - 'NO_EM_RESTART': '1', 23:13:43 INFO - 'PAGER': '/bin/cat', 23:13:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:43 INFO - 'PWD': '/builds/slave/test', 23:13:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:13:43 INFO - 'SHELL': '/bin/bash', 23:13:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0nSKspjUY/Listeners', 23:13:43 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 23:13:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:13:43 INFO - 'USER': 'cltbld', 23:13:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:13:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:43 INFO - 'XPC_FLAGS': '0x0', 23:13:43 INFO - 'XPC_SERVICE_NAME': '0', 23:13:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:13:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:13:44 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-VvDXix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:13:44 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)) 23:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:13:44 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-U7BCqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:13:44 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)) 23:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:13:44 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-dr9HVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:13:44 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)) 23:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:13:44 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-IpirhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:13:44 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)) 23:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:13:44 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-RH0n7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:13:44 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)) 23:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:13:44 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-hEJjVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:13:44 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)) 23:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:13:44 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-sRT7Fp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:13:44 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)) 23:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:13:44 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-aGNGEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:13:45 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)) 23:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:13:45 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-wt1CkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:13:45 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)) 23:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:13:45 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-QygIiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:13:45 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-JI86kn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:13:45 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)) 23:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:13:45 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-ZHiC27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:13:45 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)) 23:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:13:45 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-fC03QC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:13:45 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)) 23:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:13:45 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-UUNcPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:13:45 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)) 23:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:13:45 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-sN315Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:13:45 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)) 23:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:13:45 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-6MNNzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:13:45 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)) 23:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:13:45 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-hTPRVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:13:46 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)) 23:13:46 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)) 23:13:46 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)) 23:13:46 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)) 23:13:46 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)) 23:13:46 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)) 23:13:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:13:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:13:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:13:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:13:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:13:48 INFO - Downloading blessings-1.6.tar.gz 23:13:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:13:48 INFO - Installing collected packages: blessings 23:13:48 INFO - Running setup.py install for blessings 23:13:48 INFO - Successfully installed blessings 23:13:48 INFO - Cleaning up... 23:13:48 INFO - Return code: 0 23:13:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:13:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:13:49 INFO - Reading from file tmpfile_stdout 23:13:49 INFO - Current package versions: 23:13:49 INFO - blessings == 1.6 23:13:49 INFO - blobuploader == 1.2.4 23:13:49 INFO - docopt == 0.6.1 23:13:49 INFO - functools32 == 3.2.3-2 23:13:49 INFO - jsonschema == 2.5.1 23:13:49 INFO - manifestparser == 1.1 23:13:49 INFO - mozInstall == 1.12 23:13:49 INFO - mozcrash == 0.17 23:13:49 INFO - mozdebug == 0.1 23:13:49 INFO - mozdevice == 0.48 23:13:49 INFO - mozfile == 1.2 23:13:49 INFO - mozhttpd == 0.7 23:13:49 INFO - mozinfo == 0.9 23:13:49 INFO - mozleak == 0.1 23:13:49 INFO - mozlog == 3.3 23:13:49 INFO - moznetwork == 0.27 23:13:49 INFO - mozprocess == 0.23 23:13:49 INFO - mozprofile == 0.28 23:13:49 INFO - mozrunner == 6.12 23:13:49 INFO - mozscreenshot == 0.1 23:13:49 INFO - mozsystemmonitor == 0.3 23:13:49 INFO - moztest == 0.7 23:13:49 INFO - mozversion == 1.4 23:13:49 INFO - psutil == 3.1.1 23:13:49 INFO - requests == 1.2.3 23:13:49 INFO - wsgiref == 0.1.2 23:13:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:49 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 0x109487a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10951c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85f2575460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ebd30>, '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 0x109150db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093e3b20>, '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.q5FFcZ3xha/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.P0nSKspjUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 23:13:49 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 23:13:49 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 23:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q5FFcZ3xha/Render', 23:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:13:49 INFO - 'HOME': '/Users/cltbld', 23:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:13:49 INFO - 'LOGNAME': 'cltbld', 23:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:49 INFO - 'MOZ_NO_REMOTE': '1', 23:13:49 INFO - 'NO_EM_RESTART': '1', 23:13:49 INFO - 'PAGER': '/bin/cat', 23:13:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:49 INFO - 'PWD': '/builds/slave/test', 23:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:13:49 INFO - 'SHELL': '/bin/bash', 23:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0nSKspjUY/Listeners', 23:13:49 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 23:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:13:49 INFO - 'USER': 'cltbld', 23:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:49 INFO - 'XPC_FLAGS': '0x0', 23:13:49 INFO - 'XPC_SERVICE_NAME': '0', 23:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:49 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)) 23:13:49 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)) 23:13:49 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)) 23:13:49 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)) 23:13:49 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)) 23:13:49 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)) 23:13:49 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)) 23:13:49 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)) 23:13:49 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)) 23:13:49 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)) 23:13:49 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)) 23:13:49 INFO - Cleaning up... 23:13:49 INFO - Return code: 0 23:13:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:49 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 0x109487a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10951c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85f2575460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ebd30>, '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 0x109150db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093e3b20>, '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.q5FFcZ3xha/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.P0nSKspjUY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 23:13:49 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 23:13:49 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 23:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q5FFcZ3xha/Render', 23:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:13:49 INFO - 'HOME': '/Users/cltbld', 23:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:13:49 INFO - 'LOGNAME': 'cltbld', 23:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:49 INFO - 'MOZ_NO_REMOTE': '1', 23:13:49 INFO - 'NO_EM_RESTART': '1', 23:13:49 INFO - 'PAGER': '/bin/cat', 23:13:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:49 INFO - 'PWD': '/builds/slave/test', 23:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:13:49 INFO - 'SHELL': '/bin/bash', 23:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0nSKspjUY/Listeners', 23:13:49 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 23:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:13:49 INFO - 'USER': 'cltbld', 23:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:49 INFO - 'XPC_FLAGS': '0x0', 23:13:49 INFO - 'XPC_SERVICE_NAME': '0', 23:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:13:49 INFO - Cleaning up... 23:13:49 INFO - Return code: 0 23:13:49 INFO - Running post-action listener: _resource_record_post_action 23:13:49 INFO - Running post-action listener: _start_resource_monitoring 23:13:49 INFO - Starting resource monitoring. 23:13:49 INFO - [mozharness: 2016-07-22 06:13:49.616924Z] Finished create-virtualenv step (success) 23:13:49 INFO - [mozharness: 2016-07-22 06:13:49.617881Z] Running install step. 23:13:49 INFO - Running pre-action listener: _resource_record_pre_action 23:13:49 INFO - Running main action method: install 23:13:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:13:49 INFO - Reading from file tmpfile_stdout 23:13:49 INFO - Detecting whether we're running mozinstall >=1.0... 23:13:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:13:49 INFO - Reading from file tmpfile_stdout 23:13:49 INFO - Output received: 23:13:49 INFO - Usage: mozinstall [options] installer 23:13:49 INFO - Options: 23:13:49 INFO - -h, --help show this help message and exit 23:13:49 INFO - -d DEST, --destination=DEST 23:13:49 INFO - Directory to install application into. [default: 23:13:49 INFO - "/builds/slave/test"] 23:13:49 INFO - --app=APP Application being installed. [default: firefox] 23:13:49 INFO - mkdir: /builds/slave/test/build/application 23:13:49 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'] 23:13:49 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 23:14:25 INFO - Reading from file tmpfile_stdout 23:14:25 INFO - Output received: 23:14:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:14:25 INFO - Running post-action listener: _resource_record_post_action 23:14:25 INFO - [mozharness: 2016-07-22 06:14:25.068588Z] Finished install step (success) 23:14:25 INFO - [mozharness: 2016-07-22 06:14:25.068721Z] Running run-tests step. 23:14:25 INFO - Running pre-action listener: _resource_record_pre_action 23:14:25 INFO - Running main action method: run_tests 23:14:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:14:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:14:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:14:25 INFO - 2016-07-21 23:14:25.083 screenresolution[1921:21045] starting screenresolution argv=screenresolution get 23:14:25 INFO - 2016-07-21 23:14:25.085 screenresolution[1921:21045] Display 0: 1600x1200x32@60 23:14:25 INFO - 2016-07-21 23:14:25.091 screenresolution[1922:21047] starting screenresolution argv=screenresolution list 23:14:25 INFO - Available Modes on Display 0 23:14:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:14:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:14:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:14:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:14:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:14:25 INFO - Intel Iris: 23:14:25 INFO - Chipset Model: Intel Iris 23:14:25 INFO - Type: GPU 23:14:25 INFO - Bus: Built-In 23:14:25 INFO - VRAM (Dynamic, Max): 1536 MB 23:14:25 INFO - Vendor: Intel (0x8086) 23:14:25 INFO - Device ID: 0x0a2e 23:14:25 INFO - Revision ID: 0x0009 23:14:25 INFO - Displays: 23:14:25 INFO - Display: 23:14:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:14:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:14:25 INFO - Display Serial Number: 1600x1200 60 23:14:25 INFO - Main Display: Yes 23:14:25 INFO - Mirror: Off 23:14:25 INFO - Online: Yes 23:14:25 INFO - Rotation: Supported 23:14:25 INFO - Return code: 0 23:14:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:14:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:14:25 INFO - Python 2.7.3 23:14:25 INFO - Return code: 0 23:14:25 INFO - grabbing minidump binary from tooltool 23:14:25 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')]} 23:14:25 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 0x1093ebd30>, '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 0x109150db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093e3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:14:25 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 23:14:25 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 23:14:25 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 23:14:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:14:25 INFO - Return code: 0 23:14:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:14:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:14:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:14:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:14:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:14:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--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 23:14:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0312 --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 23:14:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q5FFcZ3xha/Render', 23:14:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:14:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:14:25 INFO - 'HOME': '/Users/cltbld', 23:14:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:14:25 INFO - 'LOGNAME': 'cltbld', 23:14:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:14:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:14:25 INFO - 'MOZ_NO_REMOTE': '1', 23:14:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:14:25 INFO - 'NO_EM_RESTART': '1', 23:14:25 INFO - 'PAGER': '/bin/cat', 23:14:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:14:25 INFO - 'PWD': '/builds/slave/test', 23:14:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:14:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:14:25 INFO - 'SHELL': '/bin/bash', 23:14:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0nSKspjUY/Listeners', 23:14:25 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 23:14:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:14:25 INFO - 'USER': 'cltbld', 23:14:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:14:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:14:25 INFO - 'XPC_FLAGS': '0x0', 23:14:25 INFO - 'XPC_SERVICE_NAME': '0', 23:14:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:14:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--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 23:14:25 INFO - mozversion application_buildid: 20160721221515 23:14:25 INFO - mozversion application_changeset: 2ce848f8b804c55792014cd9b70c9a54854bf9d7 23:14:25 INFO - mozversion application_display_name: Nightly 23:14:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:14:25 INFO - mozversion application_name: Firefox 23:14:25 INFO - mozversion application_remotingname: firefox 23:14:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 23:14:25 INFO - mozversion application_vendor: Mozilla 23:14:25 INFO - mozversion application_version: 50.0a1 23:14:25 INFO - mozversion platform_buildid: 20160721221515 23:14:25 INFO - mozversion platform_changeset: 2ce848f8b804c55792014cd9b70c9a54854bf9d7 23:14:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 23:14:25 INFO - mozversion platform_version: 50.0a1 23:14:25 INFO - using testdate: 1469168065 23:14:25 INFO - actual date: 1469168065 23:14:25 INFO - starting webserver on 'localhost:55991' 23:14:25 INFO - SUITE-START | Running 2 tests 23:14:25 INFO - TEST-START | dromaeo_css 23:14:25 INFO - operating with platform_type : mac_ 23:14:25 INFO - Initialising browser for dromaeo_css test... 23:14:25 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpybMlR2/profile http://localhost:55991/getInfo.html) 23:14:27 INFO - PROCESS | 1932 | JavaScript error: , line 0: uncaught exception: undefined 23:14:28 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 23:14:28 INFO - PROCESS | 1932 | colorDepth:24 23:14:28 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 23:14:28 INFO - PROCESS | 1932 | __metrics 23:14:28 INFO - PROCESS | 1932 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:14:28 INFO - PROCESS | 1932 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:14:28 INFO - TEST-INFO | 1932: exit 0 23:14:28 INFO - Browser initialized. 23:14:28 INFO - Running cycle 1/1 for dromaeo_css test... 23:14:28 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpybMlR2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:14:40 INFO - PROCESS | 1934 | RSS: Main: 245555200 23:14:40 INFO - PROCESS | 1934 | 23:15:31 INFO - PROCESS | 1934 | 1469168131697 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] 23:17:43 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55991/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55991/tests/dromaeo/cssquery-ext.html) 23:17:44 INFO - PROCESS | 1934 | RSS: Main: 360759296 23:17:44 INFO - PROCESS | 1934 | 23:20:48 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55991/tests/dromaeo/cssquery-ext.html (next: http://localhost:55991/tests/dromaeo/cssquery-jquery.html) 23:20:48 INFO - PROCESS | 1934 | RSS: Main: 390238208 23:20:48 INFO - PROCESS | 1934 | 23:23:51 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55991/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55991/tests/dromaeo/cssquery-mootools.html) 23:23:51 INFO - PROCESS | 1934 | RSS: Main: 381620224 23:23:51 INFO - PROCESS | 1934 | 23:26:54 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55991/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55991/tests/dromaeo/cssquery-prototype.html) 23:26:54 INFO - PROCESS | 1934 | RSS: Main: 381775872 23:26:54 INFO - PROCESS | 1934 | 23:29:58 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55991/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55991/tests/dromaeo/cssquery-yui.html) 23:29:58 INFO - PROCESS | 1934 | RSS: Main: 355205120 23:29:58 INFO - PROCESS | 1934 | 23:33:02 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55991/tests/dromaeo/cssquery-yui.html (next: http://localhost:55991/tests/dromaeo/cssquery-dojo.html) 23:33:02 INFO - PROCESS | 1934 | RSS: Main: 394231808 23:33:02 INFO - PROCESS | 1934 | 23:33:02 INFO - PROCESS | 1934 | __start_tp_report 23:33:02 INFO - PROCESS | 1934 | _x_x_mozilla_page_load 23:33:02 INFO - PROCESS | 1934 | _x_x_mozilla_page_load_details 23:33:02 INFO - PROCESS | 1934 | |i|pagename|runs| 23:33:02 INFO - PROCESS | 1934 | |0;dojo.html;3337.8984934971454;3354.446516324805;3370.427027405341;3385.4075536781056;3425.674560916712;5594.944050559493;5685.061964775812;5705.372409035002;5772.249607551023;5773.105168698848;5718.971405142968;5771.769129234836;5772.365039845614;5775.3358363788175;5787.536997040237;5185.792568297264;5215.269862219292;5243.554297884684;5250.212468129773;5313.521783039526;5201.973990130044;5262.657927234717;5278.3665960084845;5296.894062118756;5306.336707911511;5199.220116982464;5212.817551385702;5223.973880130594;5249.553787928031;5259.474052594748;5306.893862122755;5332.494670999749;5334.893302133974;5344.171653393731;5359.839204823861;5079.720615366154;5094.4650811664615;5152.149895267314;5233.084210263189;5246.108161612535;5631.549476041907;5699.401562835884;5705;5751;5755;6141.78503752371;6176.444120029174;6180.103884936704;6183.227096612924;6189.16446279751;6092.015647028447;6135.2326666699655;6183.721732522047;6187.659678717669;6206.224221972254;5856.765729370777;5877;5892.970535147297;5924.881502369928;5925.911111333334;6304.716287767062;6319.146915166394;6320.747170113144;6329.335419780857;6331.3352098029045;6047.395260474005;6105.694715264219;6124.846878828065;6136.8772624548155;6146.692665366803;4640.744759038218;6257.092721555349;6279.780207692709;6285.120083188357;6297.779577714849;5244.973775131176;6167.229096362955;6192.8761424771255;6208.348123447064;6228.439440450381;4958.281049247911;6094.45149936499;6143.6928153593035;6278.6546739929745;6282.465990390868;4889;4913.06651736169;4922.941567562991;4928.778204980785;4939.703617782944;4423.402840616475;4431.379606855043;4504.369388285642;4506.301523263899;4522.932156017662;5831.533477321804;5847.590668653239;5850.64896106235;5850.765969361178;5906.970465147732;5034.748262586856;6206.968965155235;6232.532560058014;6263.248410190715;6266.467350275187;5647.858803529944;5651.660900345993;5736.827895163152;5749.88500229993;5766.769329226868;5402.054640437907;5405.5135037846785;5413.9729301353245;5420.53925416344;5421.484958928895;5840.766369345263;5890.49930755882;6248.28144763349;6282.340354262737;6323.905141422974;6237.875242495215;6266.060090986389;6277.089821975879;6277.748890044347;6289.371062893674;6393.840153996187;6395.552311338161;6396.264429590657;6402.871942561216;6436.645984470899;6140.293866205444;6141.087130295657;6225.906611400923;6246.781362652286;6256.249250089836;6203.751849925952;6248.281447633581;6253.280872699698;6259.092431597484;6259.843503912439;6126.456603435028;6184.845378865564;6187.969060154671;6197.380261973766;6199.969000154971;6169.537284703575;6174.44430001284;6185.350538193425;6193.318734939063;6209;5818.563607729353;5884.676342801187;6026.641892803134;6030.7286172121485;6036.0644100164545;6109.34393638171;6117.1130186123655;6119.563485741553;6190.690465476799;6194.81415557534;5963.850903727268;6022.036474164112;6022.909347849787;6022.969885150546;6096 23:33:02 INFO - PROCESS | 1934 | |1;ext.html;461.24586301397204;466.461237270952;500.712090547935;508.3797766724597;524.7612336386945;30229.395412091744;32369;41870.371944420855;42994.14011719764;45207;44047.55952440482;44396.55603443964;44440.55559444405;44506.109877802504;44543.77728111363;9663.66177183797;9848.556814943344;9850.753731156707;9868.90131098687;9878.061584149531;8640;8920.955395223014;11988.760224795498;12100.939495302555;12129.150959432844;12080.51677932886;12131.514739410413;12140.696482587917;12164.574239901605;12197.695057623587;8714.738557843275;11990.580329688462;12085.15403921726;12106.668266490678;12118.757624847542;8730.825383492358;9682.46746428946;11920.701982450422;11939.164258501909;12287.508499660004;34648.96053118411;42352.30590776365;42731.57268427307;42754.78622606898;42804.785976070234;3513.325978544632;3727.683146932514;4202.138561594866;4217.599328063829;4415.66882483815;31974;32334;33255.16862078443;33256.501152482735;33325.33349333024;3232.82219477931;3402.4556071028765;3435.845386957593;3440;3440.9483857742152;470194;519113;519317.9999999962;526985;528995;60800.08799868006;61087;61172.082418763755;61173.69413152995;61281.69359153176;63379.68310158512;63391.04913426302;63394;63488;63886.68056659687;16597.253123609466;16841.242144103544;16852.831471685127;16866.83133168677;16887.577810554834;10489.580416783396;12001.759964800829;12681.809772853414;12993.675158121123;13029.739405211843;15573.377064917502;15865.920670396805;15901.125438101015;16014.558689717982;16098.517044488684;6614.867702646016;6806.965965170142;6814.182298124158;6871.969204619347;6897.517173797786;770.6801677303856;988.5255077562811;1007.3049595778889;1039.2309690828731;1087.260383386591;30610.693893060783;30679.23301917425;31230.219244518616;31457;31457.528137077963;884.6947803007952;1086.5979587552656;1127.8195488721765;1154.1055681846635;1154.9595764148214;15507.224638768243;15959.601009974844;15967.281472333541;15993.760093598605;16413.6717265654;652328.6917827092;660032;660169;661291;661521;60717.14262858961;60934.78130437367;60974.695126524086;61214.38785612176;61312.080318795255;1227.9386030698608;1277.2081309588116;1305.719270356859;1399.825021872266;1413.1379858286437;29760.25599359947;31404.52893206558;31644.841775791894;31654.525182121826;32395;49744;50297;50309.49690503048;50331;50352.49647503478;538982;541207;542851;543567;545628;26640.067597633988;26880;27139.86430067916;27296.1811145658;40218.9945251371;49313;49510;49849;50054;50082.74793130201;39797.80101099476;40145.799271004624;40720.184242472256;40817.775466734856;41436.792816035726;31676.683233168296;37559;39812.805615831574;40909.79545102255;41126.73252544403;37991.05022374463;39013.609863900994;39636.60363396329;39690;39765.80117099396;34646.82676586601;34788.652113479555;34821.82589087039;34975.82512087423;35216.471752923215;20616;23239.767602323762;24452.75547244576;25883;26624.733752662225 23:33:02 INFO - PROCESS | 1934 | |2;jquery.html;319.788939300062;363.7831453787083;373.52375720955786;381.2907991136486;401.8854626431466;15703.764443533342;16950.06774627395;20658.48353791152;21197.36407907765;21314.786852131474;18494.630107397843;20624.89687551564;20959.37121886346;21649.242276520326;21827.67258491124;5208.218767184933;5846.707664616773;6002.339742628307;6108.328083910788;6122.051082082285;5923.526117910556;6018.337982821886;6019.367966363535;6026.0659597762415;6043.939560604382;5752.453516915907;5971.044632858743;5979;6049.546284028694;6097.451229389354;5989.799075728372;6019.277686677583;6038.909416358758;6041.093835924603;6052.818415447544;5737.454941780524;5829.5044921181325;5881.470667639933;5896.469317761379;5901.3803550627;13786.448542058306;13995.16029038261;21496.462588435257;26489.337766555796;26822;2203.1958335207696;2327.290176496185;2439.2682195341326;2712.2134580971497;2969.613950186462;10119.747006324827;10255.384676919408;10589.947050264776;10629.574817007311;10699.679009629723;1878.342580096955;1921.9615607687767;1949.7757757857887;1956.6967120096408;1989.8607097503182;116628.41685791517;125157.99999999908;126080.36959815233;128144;129853.70146298605;9317.34778565494;11211.495482703298;11649.59226427071;11652.30086194814;11814.468348924147;14602.926985365006;14620.415183392544;14630.780538291934;14633.341499632543;14663.046901951338;7624.42816788743;7782.22831764649;8233.300169485541;8610.847288177956;8653.437526560754;6825.48808839339;7495.925040749632;7978.042634883851;8442.282405995558;8600.225979661735;4525.728456292633;4549.681522293474;4554.521775213555;4577.3362862384765;4635.188841952645;1766.70849309865;1795.9135893396358;1874.4282993686784;1915.5977244778474;1921.269917431367;861.3539845116163;907.5734404829719;944.7071407863584;951.6478902806006;961.6680896957624;21010.15959361639;22262.443438914157;22281.10875564959;22483.32550023502;22524.774752252597;818.4516374029415;917.1699611851285;924.1543987251724;927.8005228875824;994.4928086675703;9279.304052196112;11392.031677307355;11464.426778661034;11688.649340519798;11711.765764684658;580467;585911;593384;594770;599489;127175.36412317879;131626;131742;132310.0681571831;133009;982.2044144243185;1003.7340104872069;1011.0748664971469;1020.1277907389075;1020.9591616335412;15674.608134796721;15808.60478488047;18213;19324.03379830975;19530.511737206685;42739.78630106829;53374;53725.73137134446;54060.72969635126;54723;80111.79832302636;80488.59755701185;80649;80857.9785505367;83606.58196708978;7622.466427350032;7994.5602991836;8053.919460805551;8486.151384861465;8498.915010849812;9372.812543749224;12454.564090256797;12951.805722913976;13449.86550134486;13506.122102063335;11080.61217857371;11142.442877856236;11662.94168529152;11694.532218711156;11726.88273117292;8409.663613455637;10437.425941573287;10748.193885458779;11084.722881928017;11344.149188810707;11252.043576295779;11641.417929103342;11747.29516229029;11780.352080635646;11830.284608731858;7881.724139655084;8140.674373025013;8179.67281308741;8199.877001845096;8315.542645154575;10362;11252.66242012741;11489.195756296978;11491.31052136841;11527.76944461123 23:33:02 INFO - PROCESS | 1934 | |3;mootools.html;1566.4204244429563;1602.4311369463842;1740.7640575191613;1806.5303021214481;1949.103412430282;7214.567125972445;7650.6557204925775;8918.42030268033;8920;8928.955355223216;7857.2535609117185;8072.71745488908;8460.534670593115;8667.479951202933;8736.21374076333;2358.3043002314357;2393.401649587603;2397.652340410639;2403;2406.3382569793357;2358.363241924679;2360.9173678921243;2403.194929698553;2407.1935901473025;2411.336882357357;2095.8113769760716;2313.7223533176043;2376.8098552115875;2389.3787615219994;2405.5549723301156;2342.1802369170823;2357.1043003658588;2372.632242002492;2373.4066483379156;2385.7256415512093;2354.5997180479358;2372.0867466025534;2373.7982271506944;2385.928422147321;2390.318759153633;7335.376492998102;8991.820163596756;8993.775155621097;9011.774705632346;9017.729468115902;5216.5305122538775;5387;5397.082495975694;5397.54120899724;5400.648957817729;3654.14127679995;3673.3571624965525;3730.850765969358;3747.2318174774105;3758.4550240215017;922.9492377919212;1090.3022065877844;1091.4270008245655;1106.3472551194834;1114.7324642085841;11607.419629018515;11675.883241167565;11699.415029248503;11729.648110556782;11760.235584686969;4544.18204723153;4741.857744267678;4819.3012013258585;4842.201036828954;4856.1016212000895;9966.20270378368;9976.15202707778;9977.900220997697;9989.650362237287;9992.600295988079;5353.57171426285;5354.839354819362;5363.222332761728;5370.838874833761;5376.838694839161;4416.624586910084;4584.312353147055;4689.718616882998;4893.486183950705;4909.459959404463;3952.8418863245724;3973.543042550086;4079.530853951774;4116.958830411718;4119.279126152911;1195.1394995603152;1228.662117917562;1286.8005459153846;1304.4195333076689;1312.8293321868287;934.6962237272913;947.5688561704408;960.0927123868017;965.4882912056481;990.4799980010408;5858.209141765892;5988.520918326611;5988.550858685616;5995.43043410884;6018;440.68270844991554;443.2000239567547;454.99317510237375;463.2194751843088;465.85791333642885;3941.9947913282504;3963.286608410456;4002.399640053957;4104.445899803548;4127.050778321003;10312.639057632947;10637.255392122477;10650.733731656615;10663.946680266548;10688.839667404995;10530.15758739299;10639.361638301727;10660.307080039927;10667.14662826972;10697.83953240702;8095.635696393676;8171.10117887032;8178.46839955401;8187.0994190638985;8201;5761.193432919478;5835.2122463466885;5883.970580147072;6048.5161187105805;6092.695365231632;9607.423554586747;9860.457674827954;9904.910459849267;9932.00679932001;9970.202383809423;10083.546240419051;10177.694669159937;10183.49082545855;10188.796224075626;10201.59193632276;6960.756373527105;7192.640367981476;7193;7526.887096693445;7615.619219039137;7837.529748215125;7928.722494712657;8358.582070896553;8496.405251632326;8614.82770344602;7441.069866266716;7614.124375696866;7618.923810762043;7726.845463090819;7831;7307.561546307026;7420.777376678709;7488.101427828696;7514.211007844097;7615.961920190363;6955.652217389009;7108.182559005781;7109.431245500451;7111.182214045244;7112.217656057933;4948.331975183304;5062.620303477328;5115.820946267012;5157.355330583677;5219.0083884061905;4979.0788704089855;5021.49785021495;5184.118699820964;5194.091034068947;5295.179247216687 23:33:02 INFO - PROCESS | 1934 | |4;prototype.html;300.15656006621384;304.2302973477103;314.1643229010831;320.1819351556774;321.5048824809792;5200.219967004951;5207.973960130199;5259.526642602165;5277.3139491866095;5283.577313814895;4320.45093394897;4379.934300985487;5050.752415175249;5055.898882022358;5088.567471764904;3810.4474851146565;3923.7253392262555;3940.7635541867435;3957.544882338539;4031.737937034098;3373.5108409280638;3958;4002.6997975151835;4006.819693113817;4015.1367455996956;3813.542374915014;3914.2171565686835;3930.6785766027806;3940.1134744682504;3984.900377490557;3921.784301863396;3934.2328245992044;3956.7428117172435;3958.3072962231495;3965.4621042532804;2672.398710290189;2908.650961884566;3013.5780990661324;3466.6533346665383;3492.8602855885733;4997.225430058346;4999.400071991348;5004.974975125101;5006.924896126561;5061.645684802065;4395.78021098947;4525.411696479437;4559.176329468206;4563.178627846986;4604.562566556172;4544.977589902711;5089.770960306795;5405.702686352249;5416.916616676681;5440.836774896759;3854.5374555053295;3862.671672907806;3868.18768058708;3877.670398016172;3890.630390112963;5343.706096164709;5566.721663916788;5624.3531993820825;5674.375818659944;5696.515796157331;3499.702525285379;3691.280200360904;3816.9618303816605;3870.2646497165447;3876.7092468065007;3935.2881496549094;3943.5662077171487;3950.802459876995;3965.6034396560112;3981.960180398217;4172.332426811696;4453.710508816916;4544.454665440168;4560.840370587013;4566.1552612766745;5438.700871452108;5450.809221677222;5466.590005749585;5469.398366179717;5517.751701173457;1108.5067145120345;1210.2073142091945;1318.8351456067992;1323.814665946749;1326.6418067121824;754.7847964776705;771.3173841150622;801.6232370785722;864.8616221404545;904.4708845325426;4270.594293542109;4719;5038.924416133761;5074.949250507448;5109.20807274873;4692.671512994058;4942.9011419771405;5621.18492818539;5642.463965923231;5673.602847800615;3995.4406383105807;4441.733495990186;5116.462771409023;5117.795288188505;5188.610854185951;2716.8046059733656;2934.8826046957884;2939.4562006028955;2963.9400616571706;2970.420767950272;5150.871228219325;5785.652860828363;5793.508791219816;5818.854528636818;5823.796167134215;4705.764711764398;5418.864528386822;5719.771209151588;5732.398098199712;5785.768569257182;5347.438518955531;5766.135079738073;5775.364709881994;5820.3888591698105;5865.912011319919;4531.184386810406;4986.750662466935;5597.292825688204;5606.355269144101;5643;4870.756462176806;5261.053010458154;5509.421510741475;5584.888302234014;5590.5527557796095;5764.625299355528;5795.478406943395;5798.59409841307;5812.302523697014;5815.778686475718;2999.805012674169;3009.578658987787;3567.1094320010434;3746;3788.6021967692996;4302.193078906929;5003.624728145476;5618.971905140448;5633.549316054788;5687.1753595729215;4559.703619264737;5455.318085239345;5471.316085489314;5476.123820188751;5509.559235261089;4533.280031980785;4814.9518504814505;5460.481254280837;5485.586342009503;5496.80761173357;4476;4667.533246675441;4813.446453657874;5021.623378246573;5344.893102138013;5029.748512574283;5207.3230480037355;5287.673423693716;5495;5516.696581688046;4279.529251782364;5336.625201785168;5342.679439233659;5383.461653834585;5397.919031214614 23:33:02 INFO - PROCESS | 1934 | |5;yui.html;741.6810771368312;757.254104706864;761.3642608421967;782.7534326687097;794.098697977795;15281.465148719797;16704.415345462898;16738;16790.160491975414;16800.915995420022;15017.474388396407;15196.620084497894;15200.23998800061;15214.087154770694;15224.771628425555;730.7296300368871;735.283098978497;740.9481336306461;906.8322360363354;915.2540679346345;708.9751858684947;708.9822754431129;709.4856229233816;887.9200871921526;926.7080869526111;950.0546955779009;964.720231132974;965.874436323277;969.2778879734573;973.5181085362756;958.2142643032716;961.7451375385529;962.1773383756927;963.8554216867456;966.3670295956125;559.2254727202812;714.1786945013276;716.0655344775066;719.1585844561876;719.2699410098754;12570.874291257061;12697.238165710087;12742.554010609676;12744.36278186087;14678.119312841158;14377.424903003868;15134.545963621109;15197.392104315813;15249.16129612882;15362.923185384001;11993.700157496045;12027.879721202851;12059.397030148457;12139.15025948184;12270.202436841666;3835.271298453285;3854.1713531590844;3887.6306750858907;3896.7661940283674;4047.9392809107885;89086;112508;113608;114452;115681.42159289234;11281.153913456523;11398.829017564578;11410.315381077162;11518.654440366803;12025.879741202476;23905.7609423907;23935.52128957411;23939.28182154538;23943.52112957731;24125.638115428283;8211.917880821115;8283.95858020706;8407.705730299533;8438.57807109642;8457.57712114404;4436.622887054572;4543.68194226401;4550.226461501553;4571.5428457154685;4618.538146185355;3295.566428603517;3529;3547.5565554305713;3614.746967712235;3674.706023518112;656.7537173559915;667.2693400726197;668.0880597983814;674.2650510943096;683.9965800170967;562.9943700563024;594.5273507561499;639.6897504710212;648.7048392981183;701.5615240474704;9739.220862330996;9859.161971232526;9870;9896.60413583463;9929.453880036523;576.2681394628798;670.239278418998;702.7540360873653;714.6033951157158;727.0657205490919;3750.662440380379;4751.477337492873;4849.951500485021;4881.585065269421;4899.853004409873;93183;138018;143106;144963;147696;112741;114277.4286128564;114844;118281;118341.81658183307;103150;108667.36998945022;109229.45385273022;109382;110733;9057.81884362296;9074.727758167266;9331.346805723806;9379;9529.237660987243;61752;62902.62144216768;63061.68469157625;64972.37569060811;65377.34622653713;64019.6799016002;71143;77975.61012194902;87398.56300718455;94262;14599.489017884323;14647;14744;14763.778543322074;15092.924535377253;15452.5364239073;15728.842711572737;15809.920950395175;16267.26797294101;16283.837161628233;14373.06575072617;14374.640633984234;14768.852311477178;14908;15145.772813408028;14455.204963727096;14597.489087881873;14599;14599.270036498345;15180.848191517944;14119.223442710749;14236.359363828446;14241.430342786172;14405.495807646683;14860.777088343468;11085.667429977113;11249.606263780728;11554;11657.125715571536;12027.398630068637;11593.536258549564;11738.295702257892;11952.462139203582;11980.520779168735;11988.160828741904 23:33:02 INFO - PROCESS | 1934 | __end_tp_report 23:33:02 INFO - PROCESS | 1934 | __start_cc_report 23:33:02 INFO - PROCESS | 1934 | _x_x_mozilla_cycle_collect,1734 23:33:02 INFO - PROCESS | 1934 | __end_cc_report 23:33:02 INFO - PROCESS | 1934 | __startTimestamp1469169182365__endTimestamp 23:33:02 INFO - PROCESS | 1934 | 23:33:02 INFO - PROCESS | 1934 | ------- Summary: start ------- 23:33:02 INFO - PROCESS | 1934 | Number of tests: 6 23:33:02 INFO - PROCESS | 1934 | 23:33:02 INFO - PROCESS | 1934 | [#0] dojo.html Cycles:165 Average:5741.84 Median:5925.91 stddev:641.06 (10.8%) stddev-sans-first:614.66 23:33:02 INFO - PROCESS | 1934 | Values: 3337.9 3354.4 3370.4 3385.4 3425.7 5594.9 5685.1 5705.4 5772.2 5773.1 5719.0 5771.8 5772.4 5775.3 5787.5 5185.8 5215.3 5243.6 5250.2 5313.5 5202.0 5262.7 5278.4 5296.9 5306.3 5199.2 5212.8 5224.0 5249.6 5259.5 5306.9 5332.5 5334.9 5344.2 5359.8 5079.7 5094.5 5152.1 5233.1 5246.1 5631.5 5699.4 5705.0 5751.0 5755.0 6141.8 6176.4 6180.1 6183.2 6189.2 6092.0 6135.2 6183.7 6187.7 6206.2 5856.8 5877.0 5893.0 5924.9 5925.9 6304.7 6319.1 6320.7 6329.3 6331.3 6047.4 6105.7 6124.8 6136.9 6146.7 4640.7 6257.1 6279.8 6285.1 6297.8 5245.0 6167.2 6192.9 6208.3 6228.4 4958.3 6094.5 6143.7 6278.7 6282.5 4889.0 4913.1 4922.9 4928.8 4939.7 4423.4 4431.4 4504.4 4506.3 4522.9 5831.5 5847.6 5850.6 5850.8 5907.0 5034.7 6207.0 6232.5 6263.2 6266.5 5647.9 5651.7 5736.8 5749.9 5766.8 5402.1 5405.5 5414.0 5420.5 5421.5 5840.8 5890.5 6248.3 6282.3 6323.9 6237.9 6266.1 6277.1 6277.7 6289.4 6393.8 6395.6 6396.3 6402.9 6436.6 6140.3 6141.1 6225.9 6246.8 6256.2 6203.8 6248.3 6253.3 6259.1 6259.8 6126.5 6184.8 6188.0 6197.4 6200.0 6169.5 6174.4 6185.4 6193.3 6209.0 5818.6 5884.7 6026.6 6030.7 6036.1 6109.3 6117.1 6119.6 6190.7 6194.8 5963.9 6022.0 6022.9 6023.0 6096.0 23:33:02 INFO - PROCESS | 1934 | 23:33:02 INFO - PROCESS | 1934 | [#1] ext.html Cycles:180 Average:71112.49 Median:39663.30 stddev:153529.81 (387.1%) stddev-sans-first:153868.87 23:33:02 INFO - PROCESS | 1934 | Values: 461.2 466.5 500.7 508.4 524.8 30229.4 32369.0 41870.4 42994.1 45207.0 44047.6 44396.6 44440.6 44506.1 44543.8 9663.7 9848.6 9850.8 9868.9 9878.1 8640.0 8921.0 11988.8 12100.9 12129.2 12080.5 12131.5 12140.7 12164.6 12197.7 8714.7 11990.6 12085.2 12106.7 12118.8 8730.8 9682.5 11920.7 11939.2 12287.5 34649.0 42352.3 42731.6 42754.8 42804.8 3513.3 3727.7 4202.1 4217.6 4415.7 31974.0 32334.0 33255.2 33256.5 33325.3 3232.8 3402.5 3435.8 3440.0 3440.9 470194.0 519113.0 519318.0 526985.0 528995.0 60800.1 61087.0 61172.1 61173.7 61281.7 63379.7 63391.0 63394.0 63488.0 63886.7 16597.3 16841.2 16852.8 16866.8 16887.6 10489.6 12001.8 12681.8 12993.7 13029.7 15573.4 15865.9 15901.1 16014.6 16098.5 6614.9 6807.0 6814.2 6872.0 6897.5 770.7 988.5 1007.3 1039.2 1087.3 30610.7 30679.2 31230.2 31457.0 31457.5 884.7 1086.6 1127.8 1154.1 1155.0 15507.2 15959.6 15967.3 15993.8 16413.7 652328.7 660032.0 660169.0 661291.0 661521.0 60717.1 60934.8 60974.7 61214.4 61312.1 1227.9 1277.2 1305.7 1399.8 1413.1 29760.3 31404.5 31644.8 31654.5 32395.0 49744.0 50297.0 50309.5 50331.0 50352.5 538982.0 541207.0 542851.0 543567.0 545628.0 26640.1 26880.0 27139.9 27296.2 40219.0 49313.0 49510.0 49849.0 50054.0 50082.7 39797.8 40145.8 40720.2 40817.8 41436.8 31676.7 37559.0 39812.8 40909.8 41126.7 37991.1 39013.6 39636.6 39690.0 39765.8 34646.8 34788.7 34821.8 34975.8 35216.5 20616.0 23239.8 24452.8 25883.0 26624.7 23:33:02 INFO - PROCESS | 1934 | 23:33:02 INFO - PROCESS | 1934 | [#2] jquery.html Cycles:180 Average:35022.02 Median:14464.30 stddev:99001.99 (684.5%) stddev-sans-first:99245.42 23:33:02 INFO - PROCESS | 1934 | Values: 319.8 363.8 373.5 381.3 401.9 15703.8 16950.1 20658.5 21197.4 21314.8 18494.6 20624.9 20959.4 21649.2 21827.7 5208.2 5846.7 6002.3 6108.3 6122.1 5923.5 6018.3 6019.4 6026.1 6043.9 5752.5 5971.0 5979.0 6049.5 6097.5 5989.8 6019.3 6038.9 6041.1 6052.8 5737.5 5829.5 5881.5 5896.5 5901.4 13786.4 13995.2 21496.5 26489.3 26822.0 2203.2 2327.3 2439.3 2712.2 2969.6 10119.7 10255.4 10589.9 10629.6 10699.7 1878.3 1922.0 1949.8 1956.7 1989.9 116628.4 125158.0 126080.4 128144.0 129853.7 9317.3 11211.5 11649.6 11652.3 11814.5 14602.9 14620.4 14630.8 14633.3 14663.0 7624.4 7782.2 8233.3 8610.8 8653.4 6825.5 7495.9 7978.0 8442.3 8600.2 4525.7 4549.7 4554.5 4577.3 4635.2 1766.7 1795.9 1874.4 1915.6 1921.3 861.4 907.6 944.7 951.6 961.7 21010.2 22262.4 22281.1 22483.3 22524.8 818.5 917.2 924.2 927.8 994.5 9279.3 11392.0 11464.4 11688.6 11711.8 580467.0 585911.0 593384.0 594770.0 599489.0 127175.4 131626.0 131742.0 132310.1 133009.0 982.2 1003.7 1011.1 1020.1 1021.0 15674.6 15808.6 18213.0 19324.0 19530.5 42739.8 53374.0 53725.7 54060.7 54723.0 80111.8 80488.6 80649.0 80858.0 83606.6 7622.5 7994.6 8053.9 8486.2 8498.9 9372.8 12454.6 12951.8 13449.9 13506.1 11080.6 11142.4 11662.9 11694.5 11726.9 8409.7 10437.4 10748.2 11084.7 11344.1 11252.0 11641.4 11747.3 11780.4 11830.3 7881.7 8140.7 8179.7 8199.9 8315.5 10362.0 11252.7 11489.2 11491.3 11527.8 23:33:02 INFO - PROCESS | 1934 | 23:33:02 INFO - PROCESS | 1934 | [#3] mootools.html Cycles:180 Average:5711.26 Median:5000.29 stddev:3183.44 (63.7%) stddev-sans-first:3177.14 23:33:02 INFO - PROCESS | 1934 | Values: 1566.4 1602.4 1740.8 1806.5 1949.1 7214.6 7650.7 8918.4 8920.0 8929.0 7857.3 8072.7 8460.5 8667.5 8736.2 2358.3 2393.4 2397.7 2403.0 2406.3 2358.4 2360.9 2403.2 2407.2 2411.3 2095.8 2313.7 2376.8 2389.4 2405.6 2342.2 2357.1 2372.6 2373.4 2385.7 2354.6 2372.1 2373.8 2385.9 2390.3 7335.4 8991.8 8993.8 9011.8 9017.7 5216.5 5387.0 5397.1 5397.5 5400.6 3654.1 3673.4 3730.9 3747.2 3758.5 922.9 1090.3 1091.4 1106.3 1114.7 11607.4 11675.9 11699.4 11729.6 11760.2 4544.2 4741.9 4819.3 4842.2 4856.1 9966.2 9976.2 9977.9 9989.7 9992.6 5353.6 5354.8 5363.2 5370.8 5376.8 4416.6 4584.3 4689.7 4893.5 4909.5 3952.8 3973.5 4079.5 4117.0 4119.3 1195.1 1228.7 1286.8 1304.4 1312.8 934.7 947.6 960.1 965.5 990.5 5858.2 5988.5 5988.6 5995.4 6018.0 440.7 443.2 455.0 463.2 465.9 3942.0 3963.3 4002.4 4104.4 4127.1 10312.6 10637.3 10650.7 10663.9 10688.8 10530.2 10639.4 10660.3 10667.1 10697.8 8095.6 8171.1 8178.5 8187.1 8201.0 5761.2 5835.2 5884.0 6048.5 6092.7 9607.4 9860.5 9904.9 9932.0 9970.2 10083.5 10177.7 10183.5 10188.8 10201.6 6960.8 7192.6 7193.0 7526.9 7615.6 7837.5 7928.7 8358.6 8496.4 8614.8 7441.1 7614.1 7618.9 7726.8 7831.0 7307.6 7420.8 7488.1 7514.2 7616.0 6955.7 7108.2 7109.4 7111.2 7112.2 4948.3 5062.6 5115.8 5157.4 5219.0 4979.1 5021.5 5184.1 5194.1 5295.2 23:33:02 INFO - PROCESS | 1934 | 23:33:02 INFO - PROCESS | 1934 | [#4] prototype.html Cycles:180 Average:4404.90 Median:4991.99 stddev:1358.45 (27.2%) stddev-sans-first:1326.86 23:33:02 INFO - PROCESS | 1934 | Values: 300.2 304.2 314.2 320.2 321.5 5200.2 5208.0 5259.5 5277.3 5283.6 4320.5 4379.9 5050.8 5055.9 5088.6 3810.4 3923.7 3940.8 3957.5 4031.7 3373.5 3958.0 4002.7 4006.8 4015.1 3813.5 3914.2 3930.7 3940.1 3984.9 3921.8 3934.2 3956.7 3958.3 3965.5 2672.4 2908.7 3013.6 3466.7 3492.9 4997.2 4999.4 5005.0 5006.9 5061.6 4395.8 4525.4 4559.2 4563.2 4604.6 4545.0 5089.8 5405.7 5416.9 5440.8 3854.5 3862.7 3868.2 3877.7 3890.6 5343.7 5566.7 5624.4 5674.4 5696.5 3499.7 3691.3 3817.0 3870.3 3876.7 3935.3 3943.6 3950.8 3965.6 3982.0 4172.3 4453.7 4544.5 4560.8 4566.2 5438.7 5450.8 5466.6 5469.4 5517.8 1108.5 1210.2 1318.8 1323.8 1326.6 754.8 771.3 801.6 864.9 904.5 4270.6 4719.0 5038.9 5074.9 5109.2 4692.7 4942.9 5621.2 5642.5 5673.6 3995.4 4441.7 5116.5 5117.8 5188.6 2716.8 2934.9 2939.5 2963.9 2970.4 5150.9 5785.7 5793.5 5818.9 5823.8 4705.8 5418.9 5719.8 5732.4 5785.8 5347.4 5766.1 5775.4 5820.4 5865.9 4531.2 4986.8 5597.3 5606.4 5643.0 4870.8 5261.1 5509.4 5584.9 5590.6 5764.6 5795.5 5798.6 5812.3 5815.8 2999.8 3009.6 3567.1 3746.0 3788.6 4302.2 5003.6 5619.0 5633.5 5687.2 4559.7 5455.3 5471.3 5476.1 5509.6 4533.3 4815.0 5460.5 5485.6 5496.8 4476.0 4667.5 4813.4 5021.6 5344.9 5029.7 5207.3 5287.7 5495.0 5516.7 4279.5 5336.6 5342.7 5383.5 5397.9 23:33:02 INFO - PROCESS | 1934 | 23:33:02 INFO - PROCESS | 1934 | [#5] yui.html Cycles:180 Average:24033.65 Median:3581.15 stddev:36765.17 (1026.6%) stddev-sans-first:36826.71 23:33:02 INFO - PROCESS | 1934 | Values: 741.7 757.3 761.4 782.8 794.1 15281.5 16704.4 16738.0 16790.2 16800.9 15017.5 15196.6 15200.2 15214.1 15224.8 730.7 735.3 740.9 906.8 915.3 709.0 709.0 709.5 887.9 926.7 950.1 964.7 965.9 969.3 973.5 958.2 961.7 962.2 963.9 966.4 559.2 714.2 716.1 719.2 719.3 12570.9 12697.2 12742.6 12744.4 14678.1 14377.4 15134.5 15197.4 15249.2 15362.9 11993.7 12027.9 12059.4 12139.2 12270.2 3835.3 3854.2 3887.6 3896.8 4047.9 89086.0 112508.0 113608.0 114452.0 115681.4 11281.2 11398.8 11410.3 11518.7 12025.9 23905.8 23935.5 23939.3 23943.5 24125.6 8211.9 8284.0 8407.7 8438.6 8457.6 4436.6 4543.7 4550.2 4571.5 4618.5 3295.6 3529.0 3547.6 3614.7 3674.7 656.8 667.3 668.1 674.3 684.0 563.0 594.5 639.7 648.7 701.6 9739.2 9859.2 9870.0 9896.6 9929.5 576.3 670.2 702.8 714.6 727.1 3750.7 4751.5 4850.0 4881.6 4899.9 93183.0 138018.0 143106.0 144963.0 147696.0 112741.0 114277.4 114844.0 118281.0 118341.8 103150.0 108667.4 109229.5 109382.0 110733.0 9057.8 9074.7 9331.3 9379.0 9529.2 61752.0 62902.6 63061.7 64972.4 65377.3 64019.7 71143.0 77975.6 87398.6 94262.0 14599.5 14647.0 14744.0 14763.8 15092.9 15452.5 15728.8 15809.9 16267.3 16283.8 14373.1 14374.6 14768.9 14908.0 15145.8 14455.2 14597.5 14599.0 14599.3 15180.8 14119.2 14236.4 14241.4 14405.5 14860.8 11085.7 11249.6 11554.0 11657.1 12027.4 11593.5 11738.3 11952.5 11980.5 11988.2 23:33:02 INFO - PROCESS | 1934 | -------- Summary: end -------- 23:33:02 INFO - PROCESS | 1934 | 23:33:03 INFO - TEST-INFO | 1934: exit 0 23:33:03 INFO - TEST-OK | dromaeo_css | took 1117377ms 23:33:03 INFO - TEST-START | kraken 23:33:03 INFO - operating with platform_type : mac_ 23:33:03 INFO - Initialising browser for kraken test... 23:33:03 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpsAYY7p/profile http://localhost:55991/getInfo.html) 23:33:05 INFO - PROCESS | 1939 | JavaScript error: , line 0: uncaught exception: undefined 23:33:05 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 23:33:05 INFO - PROCESS | 1939 | colorDepth:24 23:33:05 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 23:33:05 INFO - PROCESS | 1939 | __metrics 23:33:05 INFO - PROCESS | 1939 | 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" 23:33:05 INFO - PROCESS | 1939 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:33:06 INFO - TEST-INFO | 1939: exit 0 23:33:06 INFO - Browser initialized. 23:33:06 INFO - Running cycle 1/1 for kraken test... 23:33:06 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpsAYY7p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:33:18 INFO - PROCESS | 1940 | RSS: Main: 245403648 23:33:18 INFO - PROCESS | 1940 | 23:33:20 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55991/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55991/tests/kraken/driver.html?testName=audio-beat-detection) 23:33:21 INFO - PROCESS | 1940 | RSS: Main: 324382720 23:33:21 INFO - PROCESS | 1940 | 23:33:24 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55991/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55991/tests/kraken/driver.html?testName=audio-dft) 23:33:25 INFO - PROCESS | 1940 | RSS: Main: 335638528 23:33:25 INFO - PROCESS | 1940 | 23:33:28 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55991/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55991/tests/kraken/driver.html?testName=audio-fft) 23:33:29 INFO - PROCESS | 1940 | RSS: Main: 427622400 23:33:29 INFO - PROCESS | 1940 | 23:33:31 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55991/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55991/tests/kraken/driver.html?testName=audio-oscillator) 23:33:31 INFO - PROCESS | 1940 | RSS: Main: 329961472 23:33:31 INFO - PROCESS | 1940 | 23:33:33 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55991/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55991/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:33:34 INFO - PROCESS | 1940 | RSS: Main: 334368768 23:33:34 INFO - PROCESS | 1940 | 23:33:37 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55991/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55991/tests/kraken/driver.html?testName=imaging-darkroom) 23:33:37 INFO - PROCESS | 1940 | RSS: Main: 372813824 23:33:37 INFO - PROCESS | 1940 | 23:33:40 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55991/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55991/tests/kraken/driver.html?testName=imaging-desaturate) 23:33:41 INFO - PROCESS | 1940 | RSS: Main: 372252672 23:33:41 INFO - PROCESS | 1940 | 23:33:44 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55991/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55991/tests/kraken/driver.html?testName=json-parse-financial) 23:33:44 INFO - PROCESS | 1940 | RSS: Main: 371908608 23:33:44 INFO - PROCESS | 1940 | 23:33:46 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55991/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55991/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:33:46 INFO - PROCESS | 1940 | RSS: Main: 350650368 23:33:46 INFO - PROCESS | 1940 | 23:33:48 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55991/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55991/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:33:49 INFO - PROCESS | 1940 | RSS: Main: 357269504 23:33:49 INFO - PROCESS | 1940 | 23:33:51 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55991/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55991/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:33:52 INFO - PROCESS | 1940 | RSS: Main: 355053568 23:33:52 INFO - PROCESS | 1940 | 23:33:55 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55991/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55991/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:33:56 INFO - PROCESS | 1940 | RSS: Main: 363909120 23:33:56 INFO - PROCESS | 1940 | 23:33:58 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55991/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55991/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:33:59 INFO - PROCESS | 1940 | RSS: Main: 365314048 23:33:59 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:55991/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55991/tests/kraken/driver.html?testName=ai-astar) 23:34:01 INFO - PROCESS | 1940 | RSS: Main: 408985600 23:34:01 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | __start_tp_report 23:34:01 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 23:34:01 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 23:34:01 INFO - PROCESS | 1940 | |i|pagename|runs| 23:34:01 INFO - PROCESS | 1940 | |0;ai-astar;102;111;113;112;114;112;111;113;111;113 23:34:01 INFO - PROCESS | 1940 | |1;audio-beat-detection;140;114;103;163;107;139;161;110;164;134 23:34:01 INFO - PROCESS | 1940 | |2;audio-dft;185;188;185;183;183;181;182;184;186;183 23:34:01 INFO - PROCESS | 1940 | |3;audio-fft;77;58;58;105;57;56;65;65;96;56 23:34:01 INFO - PROCESS | 1940 | |4;audio-oscillator;86;68;101;70;75;68;97;70;69;128 23:34:01 INFO - PROCESS | 1940 | |5;imaging-gaussian-blur;87;85;86;84;87;83;88;84;86;85 23:34:01 INFO - PROCESS | 1940 | |6;imaging-darkroom;93;92;92;94;94;93;94;99;93;92 23:34:01 INFO - PROCESS | 1940 | |7;imaging-desaturate;92;135;84;86;84;80;86;86;85;92 23:34:01 INFO - PROCESS | 1940 | |8;json-parse-financial;53;51;52;53;53;53;53;52;52;52 23:34:01 INFO - PROCESS | 1940 | |9;json-stringify-tinderbox;67;74;72;77;75;70;71;72;71;72 23:34:01 INFO - PROCESS | 1940 | |10;stanford-crypto-aes;82;85;85;82;84;82;86;91;104;102 23:34:01 INFO - PROCESS | 1940 | |11;stanford-crypto-ccm;135;136;145;153;150;146;142;143;181;129 23:34:01 INFO - PROCESS | 1940 | |12;stanford-crypto-pbkdf2;163;162;162;164;164;160;161;165;162;164 23:34:01 INFO - PROCESS | 1940 | |13;stanford-crypto-sha256-iterative;67;66;67;67;66;64;64;65;65;66 23:34:01 INFO - PROCESS | 1940 | __end_tp_report 23:34:01 INFO - PROCESS | 1940 | __start_cc_report 23:34:01 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,2097 23:34:01 INFO - PROCESS | 1940 | __end_cc_report 23:34:01 INFO - PROCESS | 1940 | __startTimestamp1469169241351__endTimestamp 23:34:01 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | ------- Summary: start ------- 23:34:01 INFO - PROCESS | 1940 | Number of tests: 14 23:34:01 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.50 stddev:3.39 (3.0%) stddev-sans-first:1.09 23:34:01 INFO - PROCESS | 1940 | Values: 102.0 111.0 113.0 112.0 114.0 112.0 111.0 113.0 111.0 113.0 23:34:01 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | [#1] audio-beat-detection Cycles:10 Average:133.50 Median:139.50 stddev:24.02 (17.2%) stddev-sans-first:25.37 23:34:01 INFO - PROCESS | 1940 | Values: 140.0 114.0 103.0 163.0 107.0 139.0 161.0 110.0 164.0 134.0 23:34:01 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:2.05 (1.1%) stddev-sans-first:2.15 23:34:01 INFO - PROCESS | 1940 | Values: 185.0 188.0 185.0 183.0 183.0 181.0 182.0 184.0 186.0 183.0 23:34:01 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | [#3] audio-fft Cycles:10 Average:69.30 Median:61.50 stddev:17.78 (28.9%) stddev-sans-first:18.64 23:34:01 INFO - PROCESS | 1940 | Values: 77.0 58.0 58.0 105.0 57.0 56.0 65.0 65.0 96.0 56.0 23:34:01 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | [#4] audio-oscillator Cycles:10 Average:83.20 Median:70.00 stddev:20.00 (28.6%) stddev-sans-first:21.19 23:34:01 INFO - PROCESS | 1940 | Values: 86.0 68.0 101.0 70.0 75.0 68.0 97.0 70.0 69.0 128.0 23:34:01 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.00 stddev:1.58 (1.8%) stddev-sans-first:1.58 23:34:01 INFO - PROCESS | 1940 | Values: 87.0 85.0 86.0 84.0 87.0 83.0 88.0 84.0 86.0 85.0 23:34:01 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:93.50 stddev:2.07 (2.2%) stddev-sans-first:2.18 23:34:01 INFO - PROCESS | 1940 | Values: 93.0 92.0 92.0 94.0 94.0 93.0 94.0 99.0 93.0 92.0 23:34:01 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | [#7] imaging-desaturate Cycles:10 Average:91.00 Median:86.00 stddev:15.87 (18.5%) stddev-sans-first:16.83 23:34:01 INFO - PROCESS | 1940 | Values: 92.0 135.0 84.0 86.0 84.0 80.0 86.0 86.0 85.0 92.0 23:34:01 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:53.00 stddev:0.70 (1.3%) stddev-sans-first:0.71 23:34:01 INFO - PROCESS | 1940 | Values: 53.0 51.0 52.0 53.0 53.0 53.0 53.0 52.0 52.0 52.0 23:34:01 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:72.00 stddev:2.77 (3.8%) stddev-sans-first:2.24 23:34:01 INFO - PROCESS | 1940 | Values: 67.0 74.0 72.0 77.0 75.0 70.0 71.0 72.0 71.0 72.0 23:34:01 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:84.50 stddev:8.21 (9.7%) stddev-sans-first:8.38 23:34:01 INFO - PROCESS | 1940 | Values: 82.0 85.0 85.0 82.0 84.0 82.0 86.0 91.0 104.0 102.0 23:34:01 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:145.50 stddev:14.24 (9.8%) stddev-sans-first:14.54 23:34:01 INFO - PROCESS | 1940 | Values: 135.0 136.0 145.0 153.0 150.0 146.0 142.0 143.0 181.0 129.0 23:34:01 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.70 Median:163.50 stddev:1.57 (1.0%) stddev-sans-first:1.66 23:34:01 INFO - PROCESS | 1940 | Values: 163.0 162.0 162.0 164.0 164.0 160.0 161.0 165.0 162.0 164.0 23:34:01 INFO - PROCESS | 1940 | 23:34:01 INFO - PROCESS | 1940 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:1.16 (1.8%) stddev-sans-first:1.13 23:34:01 INFO - PROCESS | 1940 | Values: 67.0 66.0 67.0 67.0 66.0 64.0 64.0 65.0 65.0 66.0 23:34:01 INFO - PROCESS | 1940 | -------- Summary: end -------- 23:34:01 INFO - PROCESS | 1940 | 23:34:02 INFO - TEST-INFO | 1940: exit 0 23:34:02 INFO - TEST-OK | kraken | took 59169ms 23:34:02 INFO - SUITE-END | took 1176s 23:34:02 INFO - Completed test suite (00:19:36) 23:34:02 INFO - javascript benchmark 23:34:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3337.8984934971454, 3354.446516324805, 3370.427027405341, 3385.4075536781056, 3425.674560916712, 5594.944050559493, 5685.061964775812, 5705.372409035002, 5772.249607551023, 5773.105168698848, 5718.971405142968, 5771.769129234836, 5772.365039845614, 5775.3358363788175, 5787.536997040237, 5185.792568297264, 5215.269862219292, 5243.554297884684, 5250.212468129773, 5313.521783039526, 5201.973990130044, 5262.657927234717, 5278.3665960084845, 5296.894062118756, 5306.336707911511, 5199.220116982464, 5212.817551385702, 5223.973880130594, 5249.553787928031, 5259.474052594748, 5306.893862122755, 5332.494670999749, 5334.893302133974, 5344.171653393731, 5359.839204823861, 5079.720615366154, 5094.4650811664615, 5152.149895267314, 5233.084210263189, 5246.108161612535, 5631.549476041907, 5699.401562835884, 5705.0, 5751.0, 5755.0, 6141.78503752371, 6176.444120029174, 6180.103884936704, 6183.227096612924, 6189.16446279751, 6092.015647028447, 6135.2326666699655, 6183.721732522047, 6187.659678717669, 6206.224221972254, 5856.765729370777, 5877.0, 5892.970535147297, 5924.881502369928, 5925.911111333334, 6304.716287767062, 6319.146915166394, 6320.747170113144, 6329.335419780857, 6331.3352098029045, 6047.395260474005, 6105.694715264219, 6124.846878828065, 6136.8772624548155, 6146.692665366803, 4640.744759038218, 6257.092721555349, 6279.780207692709, 6285.120083188357, 6297.779577714849, 5244.973775131176, 6167.229096362955, 6192.8761424771255, 6208.348123447064, 6228.439440450381, 4958.281049247911, 6094.45149936499, 6143.6928153593035, 6278.6546739929745, 6282.465990390868, 4889.0, 4913.06651736169, 4922.941567562991, 4928.778204980785, 4939.703617782944, 4423.402840616475, 4431.379606855043, 4504.369388285642, 4506.301523263899, 4522.932156017662, 5831.533477321804, 5847.590668653239, 5850.64896106235, 5850.765969361178, 5906.970465147732, 5034.748262586856, 6206.968965155235, 6232.532560058014, 6263.248410190715, 6266.467350275187, 5647.858803529944, 5651.660900345993, 5736.827895163152, 5749.88500229993, 5766.769329226868, 5402.054640437907, 5405.5135037846785, 5413.9729301353245, 5420.53925416344, 5421.484958928895, 5840.766369345263, 5890.49930755882, 6248.28144763349, 6282.340354262737, 6323.905141422974, 6237.875242495215, 6266.060090986389, 6277.089821975879, 6277.748890044347, 6289.371062893674, 6393.840153996187, 6395.552311338161, 6396.264429590657, 6402.871942561216, 6436.645984470899, 6140.293866205444, 6141.087130295657, 6225.906611400923, 6246.781362652286, 6256.249250089836, 6203.751849925952, 6248.281447633581, 6253.280872699698, 6259.092431597484, 6259.843503912439, 6126.456603435028, 6184.845378865564, 6187.969060154671, 6197.380261973766, 6199.969000154971, 6169.537284703575, 6174.44430001284, 6185.350538193425, 6193.318734939063, 6209.0, 5818.563607729353, 5884.676342801187, 6026.641892803134, 6030.7286172121485, 6036.0644100164545, 6109.34393638171, 6117.1130186123655, 6119.563485741553, 6190.690465476799, 6194.81415557534, 5963.850903727268, 6022.036474164112, 6022.909347849787, 6022.969885150546, 6096.0], "value": 5702.746956261988, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.24586301397204, 466.461237270952, 500.712090547935, 508.3797766724597, 524.7612336386945, 30229.395412091744, 32369.0, 41870.371944420855, 42994.14011719764, 45207.0, 44047.55952440482, 44396.55603443964, 44440.55559444405, 44506.109877802504, 44543.77728111363, 9663.66177183797, 9848.556814943344, 9850.753731156707, 9868.90131098687, 9878.061584149531, 8640.0, 8920.955395223014, 11988.760224795498, 12100.939495302555, 12129.150959432844, 12080.51677932886, 12131.514739410413, 12140.696482587917, 12164.574239901605, 12197.695057623587, 8714.738557843275, 11990.580329688462, 12085.15403921726, 12106.668266490678, 12118.757624847542, 8730.825383492358, 9682.46746428946, 11920.701982450422, 11939.164258501909, 12287.508499660004, 34648.96053118411, 42352.30590776365, 42731.57268427307, 42754.78622606898, 42804.785976070234, 3513.325978544632, 3727.683146932514, 4202.138561594866, 4217.599328063829, 4415.66882483815, 31974.0, 32334.0, 33255.16862078443, 33256.501152482735, 33325.33349333024, 3232.82219477931, 3402.4556071028765, 3435.845386957593, 3440.0, 3440.9483857742152, 470194.0, 519113.0, 519317.9999999962, 526985.0, 528995.0, 60800.08799868006, 61087.0, 61172.082418763755, 61173.69413152995, 61281.69359153176, 63379.68310158512, 63391.04913426302, 63394.0, 63488.0, 63886.68056659687, 16597.253123609466, 16841.242144103544, 16852.831471685127, 16866.83133168677, 16887.577810554834, 10489.580416783396, 12001.759964800829, 12681.809772853414, 12993.675158121123, 13029.739405211843, 15573.377064917502, 15865.920670396805, 15901.125438101015, 16014.558689717982, 16098.517044488684, 6614.867702646016, 6806.965965170142, 6814.182298124158, 6871.969204619347, 6897.517173797786, 770.6801677303856, 988.5255077562811, 1007.3049595778889, 1039.2309690828731, 1087.260383386591, 30610.693893060783, 30679.23301917425, 31230.219244518616, 31457.0, 31457.528137077963, 884.6947803007952, 1086.5979587552656, 1127.8195488721765, 1154.1055681846635, 1154.9595764148214, 15507.224638768243, 15959.601009974844, 15967.281472333541, 15993.760093598605, 16413.6717265654, 652328.6917827092, 660032.0, 660169.0, 661291.0, 661521.0, 60717.14262858961, 60934.78130437367, 60974.695126524086, 61214.38785612176, 61312.080318795255, 1227.9386030698608, 1277.2081309588116, 1305.719270356859, 1399.825021872266, 1413.1379858286437, 29760.25599359947, 31404.52893206558, 31644.841775791894, 31654.525182121826, 32395.0, 49744.0, 50297.0, 50309.49690503048, 50331.0, 50352.49647503478, 538982.0, 541207.0, 542851.0, 543567.0, 545628.0, 26640.067597633988, 26880.0, 27139.86430067916, 27296.1811145658, 40218.9945251371, 49313.0, 49510.0, 49849.0, 50054.0, 50082.74793130201, 39797.80101099476, 40145.799271004624, 40720.184242472256, 40817.775466734856, 41436.792816035726, 31676.683233168296, 37559.0, 39812.805615831574, 40909.79545102255, 41126.73252544403, 37991.05022374463, 39013.609863900994, 39636.60363396329, 39690.0, 39765.80117099396, 34646.82676586601, 34788.652113479555, 34821.82589087039, 34975.82512087423, 35216.471752923215, 20616.0, 23239.767602323762, 24452.75547244576, 25883.0, 26624.733752662225], "value": 20597.87883286476, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.788939300062, 363.7831453787083, 373.52375720955786, 381.2907991136486, 401.8854626431466, 15703.764443533342, 16950.06774627395, 20658.48353791152, 21197.36407907765, 21314.786852131474, 18494.630107397843, 20624.89687551564, 20959.37121886346, 21649.242276520326, 21827.67258491124, 5208.218767184933, 5846.707664616773, 6002.339742628307, 6108.328083910788, 6122.051082082285, 5923.526117910556, 6018.337982821886, 6019.367966363535, 6026.0659597762415, 6043.939560604382, 5752.453516915907, 5971.044632858743, 5979.0, 6049.546284028694, 6097.451229389354, 5989.799075728372, 6019.277686677583, 6038.909416358758, 6041.093835924603, 6052.818415447544, 5737.454941780524, 5829.5044921181325, 5881.470667639933, 5896.469317761379, 5901.3803550627, 13786.448542058306, 13995.16029038261, 21496.462588435257, 26489.337766555796, 26822.0, 2203.1958335207696, 2327.290176496185, 2439.2682195341326, 2712.2134580971497, 2969.613950186462, 10119.747006324827, 10255.384676919408, 10589.947050264776, 10629.574817007311, 10699.679009629723, 1878.342580096955, 1921.9615607687767, 1949.7757757857887, 1956.6967120096408, 1989.8607097503182, 116628.41685791517, 125157.99999999908, 126080.36959815233, 128144.0, 129853.70146298605, 9317.34778565494, 11211.495482703298, 11649.59226427071, 11652.30086194814, 11814.468348924147, 14602.926985365006, 14620.415183392544, 14630.780538291934, 14633.341499632543, 14663.046901951338, 7624.42816788743, 7782.22831764649, 8233.300169485541, 8610.847288177956, 8653.437526560754, 6825.48808839339, 7495.925040749632, 7978.042634883851, 8442.282405995558, 8600.225979661735, 4525.728456292633, 4549.681522293474, 4554.521775213555, 4577.3362862384765, 4635.188841952645, 1766.70849309865, 1795.9135893396358, 1874.4282993686784, 1915.5977244778474, 1921.269917431367, 861.3539845116163, 907.5734404829719, 944.7071407863584, 951.6478902806006, 961.6680896957624, 21010.15959361639, 22262.443438914157, 22281.10875564959, 22483.32550023502, 22524.774752252597, 818.4516374029415, 917.1699611851285, 924.1543987251724, 927.8005228875824, 994.4928086675703, 9279.304052196112, 11392.031677307355, 11464.426778661034, 11688.649340519798, 11711.765764684658, 580467.0, 585911.0, 593384.0, 594770.0, 599489.0, 127175.36412317879, 131626.0, 131742.0, 132310.0681571831, 133009.0, 982.2044144243185, 1003.7340104872069, 1011.0748664971469, 1020.1277907389075, 1020.9591616335412, 15674.608134796721, 15808.60478488047, 18213.0, 19324.03379830975, 19530.511737206685, 42739.78630106829, 53374.0, 53725.73137134446, 54060.72969635126, 54723.0, 80111.79832302636, 80488.59755701185, 80649.0, 80857.9785505367, 83606.58196708978, 7622.466427350032, 7994.5602991836, 8053.919460805551, 8486.151384861465, 8498.915010849812, 9372.812543749224, 12454.564090256797, 12951.805722913976, 13449.86550134486, 13506.122102063335, 11080.61217857371, 11142.442877856236, 11662.94168529152, 11694.532218711156, 11726.88273117292, 8409.663613455637, 10437.425941573287, 10748.193885458779, 11084.722881928017, 11344.149188810707, 11252.043576295779, 11641.417929103342, 11747.29516229029, 11780.352080635646, 11830.284608731858, 7881.724139655084, 8140.674373025013, 8179.67281308741, 8199.877001845096, 8315.542645154575, 10362.0, 11252.66242012741, 11489.195756296978, 11491.31052136841, 11527.76944461123], "value": 9558.010955384778, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1566.4204244429563, 1602.4311369463842, 1740.7640575191613, 1806.5303021214481, 1949.103412430282, 7214.567125972445, 7650.6557204925775, 8918.42030268033, 8920.0, 8928.955355223216, 7857.2535609117185, 8072.71745488908, 8460.534670593115, 8667.479951202933, 8736.21374076333, 2358.3043002314357, 2393.401649587603, 2397.652340410639, 2403.0, 2406.3382569793357, 2358.363241924679, 2360.9173678921243, 2403.194929698553, 2407.1935901473025, 2411.336882357357, 2095.8113769760716, 2313.7223533176043, 2376.8098552115875, 2389.3787615219994, 2405.5549723301156, 2342.1802369170823, 2357.1043003658588, 2372.632242002492, 2373.4066483379156, 2385.7256415512093, 2354.5997180479358, 2372.0867466025534, 2373.7982271506944, 2385.928422147321, 2390.318759153633, 7335.376492998102, 8991.820163596756, 8993.775155621097, 9011.774705632346, 9017.729468115902, 5216.5305122538775, 5387.0, 5397.082495975694, 5397.54120899724, 5400.648957817729, 3654.14127679995, 3673.3571624965525, 3730.850765969358, 3747.2318174774105, 3758.4550240215017, 922.9492377919212, 1090.3022065877844, 1091.4270008245655, 1106.3472551194834, 1114.7324642085841, 11607.419629018515, 11675.883241167565, 11699.415029248503, 11729.648110556782, 11760.235584686969, 4544.18204723153, 4741.857744267678, 4819.3012013258585, 4842.201036828954, 4856.1016212000895, 9966.20270378368, 9976.15202707778, 9977.900220997697, 9989.650362237287, 9992.600295988079, 5353.57171426285, 5354.839354819362, 5363.222332761728, 5370.838874833761, 5376.838694839161, 4416.624586910084, 4584.312353147055, 4689.718616882998, 4893.486183950705, 4909.459959404463, 3952.8418863245724, 3973.543042550086, 4079.530853951774, 4116.958830411718, 4119.279126152911, 1195.1394995603152, 1228.662117917562, 1286.8005459153846, 1304.4195333076689, 1312.8293321868287, 934.6962237272913, 947.5688561704408, 960.0927123868017, 965.4882912056481, 990.4799980010408, 5858.209141765892, 5988.520918326611, 5988.550858685616, 5995.43043410884, 6018.0, 440.68270844991554, 443.2000239567547, 454.99317510237375, 463.2194751843088, 465.85791333642885, 3941.9947913282504, 3963.286608410456, 4002.399640053957, 4104.445899803548, 4127.050778321003, 10312.639057632947, 10637.255392122477, 10650.733731656615, 10663.946680266548, 10688.839667404995, 10530.15758739299, 10639.361638301727, 10660.307080039927, 10667.14662826972, 10697.83953240702, 8095.635696393676, 8171.10117887032, 8178.46839955401, 8187.0994190638985, 8201.0, 5761.193432919478, 5835.2122463466885, 5883.970580147072, 6048.5161187105805, 6092.695365231632, 9607.423554586747, 9860.457674827954, 9904.910459849267, 9932.00679932001, 9970.202383809423, 10083.546240419051, 10177.694669159937, 10183.49082545855, 10188.796224075626, 10201.59193632276, 6960.756373527105, 7192.640367981476, 7193.0, 7526.887096693445, 7615.619219039137, 7837.529748215125, 7928.722494712657, 8358.582070896553, 8496.405251632326, 8614.82770344602, 7441.069866266716, 7614.124375696866, 7618.923810762043, 7726.845463090819, 7831.0, 7307.561546307026, 7420.777376678709, 7488.101427828696, 7514.211007844097, 7615.961920190363, 6955.652217389009, 7108.182559005781, 7109.431245500451, 7111.182214045244, 7112.217656057933, 4948.331975183304, 5062.620303477328, 5115.820946267012, 5157.355330583677, 5219.0083884061905, 4979.0788704089855, 5021.49785021495, 5184.118699820964, 5194.091034068947, 5295.179247216687], "value": 4534.788699554244, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.15656006621384, 304.2302973477103, 314.1643229010831, 320.1819351556774, 321.5048824809792, 5200.219967004951, 5207.973960130199, 5259.526642602165, 5277.3139491866095, 5283.577313814895, 4320.45093394897, 4379.934300985487, 5050.752415175249, 5055.898882022358, 5088.567471764904, 3810.4474851146565, 3923.7253392262555, 3940.7635541867435, 3957.544882338539, 4031.737937034098, 3373.5108409280638, 3958.0, 4002.6997975151835, 4006.819693113817, 4015.1367455996956, 3813.542374915014, 3914.2171565686835, 3930.6785766027806, 3940.1134744682504, 3984.900377490557, 3921.784301863396, 3934.2328245992044, 3956.7428117172435, 3958.3072962231495, 3965.4621042532804, 2672.398710290189, 2908.650961884566, 3013.5780990661324, 3466.6533346665383, 3492.8602855885733, 4997.225430058346, 4999.400071991348, 5004.974975125101, 5006.924896126561, 5061.645684802065, 4395.78021098947, 4525.411696479437, 4559.176329468206, 4563.178627846986, 4604.562566556172, 4544.977589902711, 5089.770960306795, 5405.702686352249, 5416.916616676681, 5440.836774896759, 3854.5374555053295, 3862.671672907806, 3868.18768058708, 3877.670398016172, 3890.630390112963, 5343.706096164709, 5566.721663916788, 5624.3531993820825, 5674.375818659944, 5696.515796157331, 3499.702525285379, 3691.280200360904, 3816.9618303816605, 3870.2646497165447, 3876.7092468065007, 3935.2881496549094, 3943.5662077171487, 3950.802459876995, 3965.6034396560112, 3981.960180398217, 4172.332426811696, 4453.710508816916, 4544.454665440168, 4560.840370587013, 4566.1552612766745, 5438.700871452108, 5450.809221677222, 5466.590005749585, 5469.398366179717, 5517.751701173457, 1108.5067145120345, 1210.2073142091945, 1318.8351456067992, 1323.814665946749, 1326.6418067121824, 754.7847964776705, 771.3173841150622, 801.6232370785722, 864.8616221404545, 904.4708845325426, 4270.594293542109, 4719.0, 5038.924416133761, 5074.949250507448, 5109.20807274873, 4692.671512994058, 4942.9011419771405, 5621.18492818539, 5642.463965923231, 5673.602847800615, 3995.4406383105807, 4441.733495990186, 5116.462771409023, 5117.795288188505, 5188.610854185951, 2716.8046059733656, 2934.8826046957884, 2939.4562006028955, 2963.9400616571706, 2970.420767950272, 5150.871228219325, 5785.652860828363, 5793.508791219816, 5818.854528636818, 5823.796167134215, 4705.764711764398, 5418.864528386822, 5719.771209151588, 5732.398098199712, 5785.768569257182, 5347.438518955531, 5766.135079738073, 5775.364709881994, 5820.3888591698105, 5865.912011319919, 4531.184386810406, 4986.750662466935, 5597.292825688204, 5606.355269144101, 5643.0, 4870.756462176806, 5261.053010458154, 5509.421510741475, 5584.888302234014, 5590.5527557796095, 5764.625299355528, 5795.478406943395, 5798.59409841307, 5812.302523697014, 5815.778686475718, 2999.805012674169, 3009.578658987787, 3567.1094320010434, 3746.0, 3788.6021967692996, 4302.193078906929, 5003.624728145476, 5618.971905140448, 5633.549316054788, 5687.1753595729215, 4559.703619264737, 5455.318085239345, 5471.316085489314, 5476.123820188751, 5509.559235261089, 4533.280031980785, 4814.9518504814505, 5460.481254280837, 5485.586342009503, 5496.80761173357, 4476.0, 4667.533246675441, 4813.446453657874, 5021.623378246573, 5344.893102138013, 5029.748512574283, 5207.3230480037355, 5287.673423693716, 5495.0, 5516.696581688046, 4279.529251782364, 5336.625201785168, 5342.679439233659, 5383.461653834585, 5397.919031214614], "value": 3972.5856315412825, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.6810771368312, 757.254104706864, 761.3642608421967, 782.7534326687097, 794.098697977795, 15281.465148719797, 16704.415345462898, 16738.0, 16790.160491975414, 16800.915995420022, 15017.474388396407, 15196.620084497894, 15200.23998800061, 15214.087154770694, 15224.771628425555, 730.7296300368871, 735.283098978497, 740.9481336306461, 906.8322360363354, 915.2540679346345, 708.9751858684947, 708.9822754431129, 709.4856229233816, 887.9200871921526, 926.7080869526111, 950.0546955779009, 964.720231132974, 965.874436323277, 969.2778879734573, 973.5181085362756, 958.2142643032716, 961.7451375385529, 962.1773383756927, 963.8554216867456, 966.3670295956125, 559.2254727202812, 714.1786945013276, 716.0655344775066, 719.1585844561876, 719.2699410098754, 12570.874291257061, 12697.238165710087, 12742.554010609676, 12744.36278186087, 14678.119312841158, 14377.424903003868, 15134.545963621109, 15197.392104315813, 15249.16129612882, 15362.923185384001, 11993.700157496045, 12027.879721202851, 12059.397030148457, 12139.15025948184, 12270.202436841666, 3835.271298453285, 3854.1713531590844, 3887.6306750858907, 3896.7661940283674, 4047.9392809107885, 89086.0, 112508.0, 113608.0, 114452.0, 115681.42159289234, 11281.153913456523, 11398.829017564578, 11410.315381077162, 11518.654440366803, 12025.879741202476, 23905.7609423907, 23935.52128957411, 23939.28182154538, 23943.52112957731, 24125.638115428283, 8211.917880821115, 8283.95858020706, 8407.705730299533, 8438.57807109642, 8457.57712114404, 4436.622887054572, 4543.68194226401, 4550.226461501553, 4571.5428457154685, 4618.538146185355, 3295.566428603517, 3529.0, 3547.5565554305713, 3614.746967712235, 3674.706023518112, 656.7537173559915, 667.2693400726197, 668.0880597983814, 674.2650510943096, 683.9965800170967, 562.9943700563024, 594.5273507561499, 639.6897504710212, 648.7048392981183, 701.5615240474704, 9739.220862330996, 9859.161971232526, 9870.0, 9896.60413583463, 9929.453880036523, 576.2681394628798, 670.239278418998, 702.7540360873653, 714.6033951157158, 727.0657205490919, 3750.662440380379, 4751.477337492873, 4849.951500485021, 4881.585065269421, 4899.853004409873, 93183.0, 138018.0, 143106.0, 144963.0, 147696.0, 112741.0, 114277.4286128564, 114844.0, 118281.0, 118341.81658183307, 103150.0, 108667.36998945022, 109229.45385273022, 109382.0, 110733.0, 9057.81884362296, 9074.727758167266, 9331.346805723806, 9379.0, 9529.237660987243, 61752.0, 62902.62144216768, 63061.68469157625, 64972.37569060811, 65377.34622653713, 64019.6799016002, 71143.0, 77975.61012194902, 87398.56300718455, 94262.0, 14599.489017884323, 14647.0, 14744.0, 14763.778543322074, 15092.924535377253, 15452.5364239073, 15728.842711572737, 15809.920950395175, 16267.26797294101, 16283.837161628233, 14373.06575072617, 14374.640633984234, 14768.852311477178, 14908.0, 15145.772813408028, 14455.204963727096, 14597.489087881873, 14599.0, 14599.270036498345, 15180.848191517944, 14119.223442710749, 14236.359363828446, 14241.430342786172, 14405.495807646683, 14860.777088343468, 11085.667429977113, 11249.606263780728, 11554.0, 11657.125715571536, 12027.398630068637, 11593.536258549564, 11738.295702257892, 11952.462139203582, 11980.520779168735, 11988.160828741904], "value": 7981.080743816425, "unit": "score"}], "name": "dromaeo_css", "value": 7379.085332137939, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 113.0, 112.0, 114.0, 112.0, 111.0, 113.0, 111.0, 113.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 114.0, 103.0, 163.0, 107.0, 139.0, 161.0, 110.0, 164.0, 134.0], "value": 133.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 188.0, 185.0, 183.0, 183.0, 181.0, 182.0, 184.0, 186.0, 183.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 58.0, 58.0, 105.0, 57.0, 56.0, 65.0, 65.0, 96.0, 56.0], "value": 69.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 68.0, 101.0, 70.0, 75.0, 68.0, 97.0, 70.0, 69.0, 128.0], "value": 83.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 86.0, 84.0, 87.0, 83.0, 88.0, 84.0, 86.0, 85.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 92.0, 94.0, 94.0, 93.0, 94.0, 99.0, 93.0, 92.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 135.0, 84.0, 86.0, 84.0, 80.0, 86.0, 86.0, 85.0, 92.0], "value": 91.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 52.0, 53.0, 53.0, 53.0, 53.0, 52.0, 52.0, 52.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 72.0, 77.0, 75.0, 70.0, 71.0, 72.0, 71.0, 72.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 85.0, 82.0, 84.0, 82.0, 86.0, 91.0, 104.0, 102.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 136.0, 145.0, 153.0, 150.0, 146.0, 142.0, 143.0, 181.0, 129.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 164.0, 164.0, 160.0, 161.0, 165.0, 162.0, 164.0], "value": 162.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 67.0, 66.0, 64.0, 64.0, 65.0, 65.0, 66.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1438.5000000000002, "alertThreshold": 2.0}]} 23:34:02 INFO - Return code: 0 23:34:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:34:02 INFO - # TBPL SUCCESS # 23:34:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:34:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:34:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:34:02 INFO - 2016-07-21 23:34:02.687 screenresolution[1944:23151] starting screenresolution argv=screenresolution get 23:34:02 INFO - 2016-07-21 23:34:02.690 screenresolution[1944:23151] Display 0: 1600x1200x32@60 23:34:02 INFO - 2016-07-21 23:34:02.695 screenresolution[1945:23154] starting screenresolution argv=screenresolution list 23:34:02 INFO - Available Modes on Display 0 23:34:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:34:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:34:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:34:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:34:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:34:02 INFO - Intel Iris: 23:34:02 INFO - Chipset Model: Intel Iris 23:34:02 INFO - Type: GPU 23:34:02 INFO - Bus: Built-In 23:34:02 INFO - VRAM (Dynamic, Max): 1536 MB 23:34:02 INFO - Vendor: Intel (0x8086) 23:34:02 INFO - Device ID: 0x0a2e 23:34:02 INFO - Revision ID: 0x0009 23:34:02 INFO - Displays: 23:34:02 INFO - Display: 23:34:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:34:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:34:02 INFO - Display Serial Number: 1600x1200 60 23:34:02 INFO - Main Display: Yes 23:34:02 INFO - Mirror: Off 23:34:02 INFO - Online: Yes 23:34:02 INFO - Rotation: Supported 23:34:02 INFO - Return code: 0 23:34:02 INFO - Running post-action listener: _resource_record_post_action 23:34:02 INFO - [mozharness: 2016-07-22 06:34:02.929289Z] Finished run-tests step (success) 23:34:02 INFO - Running post-run listener: _resource_record_post_run 23:34:02 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 921600; Write bytes: 706231296; Read time: 6; Write time: 1963 23:34:02 INFO - TinderboxPrint: CPU usage
53.4% 23:34:02 INFO - TinderboxPrint: I/O read bytes / time
921,600 / 6 23:34:02 INFO - TinderboxPrint: I/O write bytes / time
706,231,296 / 1,963 23:34:02 INFO - TinderboxPrint: CPU idle
2,260.4 (46.6%) 23:34:02 INFO - TinderboxPrint: CPU system
969.5 (20.0%) 23:34:02 INFO - TinderboxPrint: CPU user
1,619.8 (33.4%) 23:34:02 INFO - TinderboxPrint: Swap in / out
408,322,048 / 0 23:34:02 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 401261056; Write bytes: 396445184; Read time: 30985; Write time: 817 23:34:03 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 921600; Write bytes: 294221312; Read time: 6; Write time: 1103 23:34:03 INFO - Running post-run listener: _upload_blobber_files 23:34:03 INFO - Blob upload gear active. 23:34:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:34:03 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 23:34:03 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:34:03 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:34:04 INFO - (blobuploader) - INFO - Open directory for files ... 23:34:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:34:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:34:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:34:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:34:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:34:05 INFO - (blobuploader) - INFO - Done attempting. 23:34:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:34:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:34:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:34:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:34:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:34:06 INFO - (blobuploader) - INFO - Done attempting. 23:34:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:34:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:34:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:34:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:34:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:34:10 INFO - (blobuploader) - INFO - Done attempting. 23:34:10 INFO - (blobuploader) - INFO - Iteration through files over. 23:34:10 INFO - Return code: 0 23:34:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:34:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:34:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5a1b072828ced7920edb37a88797d7e236b1ce4bf24ddbb16393bee8dd8eea12b1549236f3766316269a60aea9d308c5e6501d4ddf138884c3b678b77b7b1375", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/439bac49119c8392d8612a9ebf77125940db5a7e66b8d336cbe38eb4b8d683711c68e75455f2a2dce7e8bd83b7aad8ec7622cf8d07d652b0fc6be2c706a16d6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/64733a0fb6893400b7238e99218640f0e540c91fb826a1ea2b97fe5063e6fc11d52fe55fb5aa9af9a6da4282214ea440b8aac2ce42d883ecceb5d5688e3fb042"} 23:34:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:34:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:34:10 INFO - Contents: 23:34:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5a1b072828ced7920edb37a88797d7e236b1ce4bf24ddbb16393bee8dd8eea12b1549236f3766316269a60aea9d308c5e6501d4ddf138884c3b678b77b7b1375", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/439bac49119c8392d8612a9ebf77125940db5a7e66b8d336cbe38eb4b8d683711c68e75455f2a2dce7e8bd83b7aad8ec7622cf8d07d652b0fc6be2c706a16d6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/64733a0fb6893400b7238e99218640f0e540c91fb826a1ea2b97fe5063e6fc11d52fe55fb5aa9af9a6da4282214ea440b8aac2ce42d883ecceb5d5688e3fb042"} 23:34:10 INFO - Running post-run listener: copy_logs_to_upload_dir 23:34:10 INFO - Copying logs to upload dir... 23:34:10 INFO - mkdir: /builds/slave/test/build/upload/logs 23:34:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1327.865673 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 7 secs) (at 2016-07-21 23:34:10.256148) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 23:34:10.259505) ========= 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.q5FFcZ3xha/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.P0nSKspjUY/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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/Autoland/sha512/5a1b072828ced7920edb37a88797d7e236b1ce4bf24ddbb16393bee8dd8eea12b1549236f3766316269a60aea9d308c5e6501d4ddf138884c3b678b77b7b1375", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/439bac49119c8392d8612a9ebf77125940db5a7e66b8d336cbe38eb4b8d683711c68e75455f2a2dce7e8bd83b7aad8ec7622cf8d07d652b0fc6be2c706a16d6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/64733a0fb6893400b7238e99218640f0e540c91fb826a1ea2b97fe5063e6fc11d52fe55fb5aa9af9a6da4282214ea440b8aac2ce42d883ecceb5d5688e3fb042"} build_url:https://queue.taskcluster.net/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010663 build_url: 'https://queue.taskcluster.net/v1/task/aSRfr_KqRFulWQSsoN08AQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5a1b072828ced7920edb37a88797d7e236b1ce4bf24ddbb16393bee8dd8eea12b1549236f3766316269a60aea9d308c5e6501d4ddf138884c3b678b77b7b1375", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/439bac49119c8392d8612a9ebf77125940db5a7e66b8d336cbe38eb4b8d683711c68e75455f2a2dce7e8bd83b7aad8ec7622cf8d07d652b0fc6be2c706a16d6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/64733a0fb6893400b7238e99218640f0e540c91fb826a1ea2b97fe5063e6fc11d52fe55fb5aa9af9a6da4282214ea440b8aac2ce42d883ecceb5d5688e3fb042"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 23:34:10.291824) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 23:34:10.292226) ========= 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.q5FFcZ3xha/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.P0nSKspjUY/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.005238 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 23:34:10.342328) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 23:34:10.342838) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 23:34:10.343434) ========= ========= Total master_lag: 0.18 =========