builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0011 starttime: 1469615423.66 results: success (0) buildid: 20160721093519 builduid: 5bc60eb65bb94223a55570214e3b6eb9 revision: 67186c2d647ed2807eacc51cc834ca7932d38fba ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 03:30:23.657568) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 03:30:23.657985) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-07-27 03:30:23.658285) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-07-27 03:30:25.659699) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 03:30:25.660152) ========= 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.2wrSTncfwW/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.qYV5sk5pRc/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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-27 03:30:25-- 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% 147M=0s 2016-07-27 03:30:25 (147 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.075165 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 03:30:25.758614) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 03:30:25.759179) ========= 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.2wrSTncfwW/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.qYV5sk5pRc/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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.020735 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 03:30:25.810575) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-27 03:30:25.811169) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 67186c2d647ed2807eacc51cc834ca7932d38fba --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 67186c2d647ed2807eacc51cc834ca7932d38fba --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2wrSTncfwW/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.qYV5sk5pRc/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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-27 03:30:25,891 truncating revision to first 12 chars 2016-07-27 03:30:25,891 Setting DEBUG logging. 2016-07-27 03:30:25,891 attempt 1/10 2016-07-27 03:30:25,891 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/67186c2d647e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-27 03:30:27,213 unpacking tar archive at: autoland-67186c2d647e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.575325 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-27 03:30:27.409789) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 03:30:27.410120) ========= script_repo_revision: 67186c2d647ed2807eacc51cc834ca7932d38fba ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 03:30:27.410630) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 03:30:27.410958) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 03:30:27.429265) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-27 03:30:27.429649) ========= /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.2wrSTncfwW/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.qYV5sk5pRc/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 03:30:27 INFO - MultiFileLogger online at 20160727 03:30:27 in /builds/slave/test 03:30:27 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 03:30:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:30:27 INFO - {'append_to_log': False, 03:30:27 INFO - 'base_work_dir': '/builds/slave/test', 03:30:27 INFO - 'blob_upload_branch': 'Autoland', 03:30:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:30:27 INFO - 'branch': 'Autoland', 03:30:27 INFO - 'buildbot_json_path': 'buildprops.json', 03:30:27 INFO - 'config_files': ('talos/mac_config.py',), 03:30:27 INFO - 'default_actions': ('clobber', 03:30:27 INFO - 'read-buildbot-config', 03:30:27 INFO - 'download-and-extract', 03:30:27 INFO - 'populate-webroot', 03:30:27 INFO - 'create-virtualenv', 03:30:27 INFO - 'install', 03:30:27 INFO - 'run-tests'), 03:30:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:30:27 INFO - 'download_minidump_stackwalk': True, 03:30:27 INFO - 'download_symbols': 'ondemand', 03:30:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:30:27 INFO - 'tooltool.py': '/tools/tooltool.py', 03:30:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:30:27 INFO - '/tools/misc-python/virtualenv.py')}, 03:30:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:30:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:30:27 INFO - 'installer_path': 'installer.exe', 03:30:27 INFO - 'log_level': 'info', 03:30:27 INFO - 'log_name': 'talos', 03:30:27 INFO - 'log_to_console': True, 03:30:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:30:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:30:27 INFO - 'opt_config_files': (), 03:30:27 INFO - 'pip_index': False, 03:30:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:30:27 INFO - 'cmd': ('bash', 03:30:27 INFO - '-c', 03:30:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:30:27 INFO - 'enabled': True, 03:30:27 INFO - 'halt_on_failure': False, 03:30:27 INFO - 'name': 'check_screen_resolution'},), 03:30:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:30:27 INFO - 'cmd': ('bash', 03:30:27 INFO - '-c', 03:30:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:30:27 INFO - 'enabled': True, 03:30:27 INFO - 'halt_on_failure': False, 03:30:27 INFO - 'name': 'check_screen_resolution'},), 03:30:27 INFO - 'run_cmd_checks_enabled': True, 03:30:27 INFO - 'sps_profile': False, 03:30:27 INFO - 'sps_profile_interval': 0, 03:30:27 INFO - 'suite': 'dromaeojs', 03:30:27 INFO - 'system_bits': '32', 03:30:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:30:27 INFO - 'title': 't-yosemite-r7-0011', 03:30:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:30:27 INFO - 'use_talos_json': True, 03:30:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:30:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:30:27 INFO - 'work_dir': 'build'} 03:30:27 INFO - [mozharness: 2016-07-27 10:30:27.623514Z] Running clobber step. 03:30:27 INFO - Running pre-action listener: _resource_record_pre_action 03:30:27 INFO - Running main action method: clobber 03:30:27 INFO - rmtree: /builds/slave/test/build 03:30:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:30:29 INFO - Running post-action listener: _resource_record_post_action 03:30:29 INFO - [mozharness: 2016-07-27 10:30:29.627354Z] Finished clobber step (success) 03:30:29 INFO - [mozharness: 2016-07-27 10:30:29.627492Z] Running read-buildbot-config step. 03:30:29 INFO - Running pre-action listener: _resource_record_pre_action 03:30:29 INFO - Running main action method: read_buildbot_config 03:30:29 INFO - Using buildbot properties: 03:30:29 INFO - { 03:30:29 INFO - "project": "", 03:30:29 INFO - "product": "firefox", 03:30:29 INFO - "script_repo_revision": "production", 03:30:29 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 03:30:29 INFO - "repository": "", 03:30:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 03:30:29 INFO - "buildid": "20160721093519", 03:30:29 INFO - "pgo_build": "False", 03:30:29 INFO - "basedir": "/builds/slave/test", 03:30:29 INFO - "buildnumber": 60, 03:30:29 INFO - "slavename": "t-yosemite-r7-0011", 03:30:29 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 03:30:29 INFO - "platform": "yosemite_r7", 03:30:29 INFO - "scheduler": "tests-autoland-macosx64-talos", 03:30:29 INFO - "branch": "autoland", 03:30:29 INFO - "repo_path": "integration/autoland", 03:30:29 INFO - "revision": "67186c2d647ed2807eacc51cc834ca7932d38fba", 03:30:29 INFO - "stage_platform": "macosx64", 03:30:29 INFO - "builduid": "5bc60eb65bb94223a55570214e3b6eb9", 03:30:29 INFO - "slavebuilddir": "test" 03:30:29 INFO - } 03:30:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 03:30:29 INFO - Running post-action listener: _resource_record_post_action 03:30:29 INFO - [mozharness: 2016-07-27 10:30:29.630737Z] Finished read-buildbot-config step (success) 03:30:29 INFO - [mozharness: 2016-07-27 10:30:29.630874Z] Running download-and-extract step. 03:30:29 INFO - Running pre-action listener: _resource_record_pre_action 03:30:29 INFO - Running main action method: download_and_extract 03:30:29 INFO - mkdir: /builds/slave/test/build/tests 03:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:29 INFO - https://queue.taskcluster.net/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:30:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 03:30:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 03:30:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/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 03:30:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/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 03:30:31 INFO - Downloaded 1199 bytes. 03:30:31 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:30:31 INFO - Using the following test package requirements: 03:30:31 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:30:31 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:30:31 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 03:30:31 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:30:31 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 03:30:31 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:30:31 INFO - u'jsshell-mac.zip'], 03:30:31 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:30:31 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 03:30:31 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:30:31 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:30:31 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 03:30:31 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:30:31 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 03:30:31 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:30:31 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 03:30:31 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:30:31 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 03:30:31 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:30:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:31 INFO - https://queue.taskcluster.net/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:30:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:30:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:30:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/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 03:30:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/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 03:30:32 INFO - Downloaded 18806639 bytes. 03:30:32 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'] 03:30:32 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 03:30:35 INFO - Return code: 0 03:30:35 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 03:30:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:35 INFO - https://queue.taskcluster.net/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:30:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:30:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:30:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/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 03:30:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/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 03:30:36 INFO - Downloaded 18806639 bytes. 03:30:36 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'] 03:30:36 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 03:30:39 INFO - Return code: 0 03:30: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')]} 03:30:39 INFO - https://queue.taskcluster.net/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:30:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:30:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:30:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/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 03:30:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/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 03:30:40 INFO - Downloaded 14403317 bytes. 03:30:40 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'] 03:30:40 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 03:30:41 INFO - Return code: 0 03:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:41 INFO - https://queue.taskcluster.net/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 03:30:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:30:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:30:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 03:30:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PeSldl3CT4SzR7AfH_8CCA/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 03:30:44 INFO - Downloaded 112792314 bytes. 03:30:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:30:44 INFO - mkdir: /builds/slave/test/properties 03:30:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:30:44 INFO - Writing to file /builds/slave/test/properties/build_url 03:30:44 INFO - Contents: 03:30:44 INFO - build_url:https://queue.taskcluster.net/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:30:44 INFO - Running post-action listener: _resource_record_post_action 03:30:44 INFO - Running post-action listener: set_extra_try_arguments 03:30:44 INFO - [mozharness: 2016-07-27 10:30:44.678781Z] Finished download-and-extract step (success) 03:30:44 INFO - [mozharness: 2016-07-27 10:30:44.678948Z] Running populate-webroot step. 03:30:44 INFO - Running pre-action listener: _resource_record_pre_action 03:30:44 INFO - Running main action method: populate_webroot 03:30:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:30:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:30:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:30:44 INFO - '--tppagecycles', 03:30:44 INFO - '7'], 03:30:44 INFO - 'tests': ['tsvgm']}}, 03:30:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:30:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:30:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:30:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:30:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:30:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:30:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:44 INFO - 'talos_options': ['--disable-e10s'], 03:30:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:30:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:30:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:44 INFO - 'talos_options': ['--disable-e10s'], 03:30:44 INFO - 'tests': ['damp', 'tps']}, 03:30:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:44 INFO - 'tests': ['damp', 'tps']}, 03:30:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:30:44 INFO - 'tests': ['dromaeo_dom']}, 03:30:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:30:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:30:44 INFO - 'tests': ['basic_compositor_video']}, 03:30:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:30:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:30:44 INFO - 'tests': ['a11yr', 03:30:44 INFO - 'ts_paint', 03:30:44 INFO - 'tpaint', 03:30:44 INFO - 'sessionrestore', 03:30:44 INFO - 'sessionrestore_no_auto_restore', 03:30:44 INFO - 'tabpaint']}, 03:30:44 INFO - 'other-e10s': {'tests': ['a11yr', 03:30:44 INFO - 'ts_paint', 03:30:44 INFO - 'tpaint', 03:30:44 INFO - 'sessionrestore', 03:30:44 INFO - 'sessionrestore_no_auto_restore', 03:30:44 INFO - 'tabpaint']}, 03:30:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:30:44 INFO - 'ts_paint', 03:30:44 INFO - 'tpaint', 03:30:44 INFO - 'sessionrestore', 03:30:44 INFO - 'sessionrestore_no_auto_restore', 03:30:44 INFO - 'tabpaint']}, 03:30:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:30:44 INFO - 'ts_paint', 03:30:44 INFO - 'tpaint', 03:30:44 INFO - 'sessionrestore', 03:30:44 INFO - 'sessionrestore_no_auto_restore', 03:30:44 INFO - 'tabpaint']}, 03:30:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:30:44 INFO - 'tests': ['a11yr', 03:30:44 INFO - 'ts_paint', 03:30:44 INFO - 'tpaint', 03:30:44 INFO - 'sessionrestore', 03:30:44 INFO - 'sessionrestore_no_auto_restore', 03:30:44 INFO - 'tabpaint']}, 03:30:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:30:44 INFO - 'tests': ['a11yr', 03:30:44 INFO - 'ts_paint', 03:30:44 INFO - 'tpaint', 03:30:44 INFO - 'sessionrestore', 03:30:44 INFO - 'sessionrestore_no_auto_restore', 03:30:44 INFO - 'tabpaint']}, 03:30:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:30:44 INFO - 'tests': ['tsvgx', 03:30:44 INFO - 'tsvgr_opacity', 03:30:44 INFO - 'tart', 03:30:44 INFO - 'tscrollx', 03:30:44 INFO - 'cart']}, 03:30:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:30:44 INFO - 'tsvgr_opacity', 03:30:44 INFO - 'tart', 03:30:44 INFO - 'tscrollx', 03:30:44 INFO - 'cart']}, 03:30:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:44 INFO - 'talos_options': ['--disable-e10s'], 03:30:44 INFO - 'tests': ['tp5o']}, 03:30:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:44 INFO - 'tests': ['tp5o']}, 03:30:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:44 INFO - 'talos_options': ['--disable-e10s', 03:30:44 INFO - '--xperf_path', 03:30:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:30:44 INFO - 'tests': ['tp5n']}, 03:30:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:30:44 INFO - 'talos_options': ['--xperf_path', 03:30:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:30:44 INFO - 'tests': ['tp5n']}}, 03:30:44 INFO - 'talos.zip': {'path': '', 03:30:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:30:44 INFO - Running post-action listener: _resource_record_post_action 03:30:44 INFO - [mozharness: 2016-07-27 10:30:44.697952Z] Finished populate-webroot step (success) 03:30:44 INFO - [mozharness: 2016-07-27 10:30:44.698082Z] Running create-virtualenv step. 03:30:44 INFO - Running pre-action listener: _resource_record_pre_action 03:30:44 INFO - Running main action method: create_virtualenv 03:30:44 INFO - Creating virtualenv /builds/slave/test/build/venv 03:30:44 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 03:30:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:30:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:30:44 INFO - Using real prefix '/tools/python27' 03:30:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:30:45 INFO - Installing distribute.............................................................................................................................................................................................done. 03:30:48 INFO - Installing pip.................done. 03:30:48 INFO - Return code: 0 03:30:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:30:48 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')]} 03:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:48 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')]} 03:30:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:48 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 0x105742a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffba85a4590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056a6d30>, '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 0x10540bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10569eb20>, '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.2wrSTncfwW/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.qYV5sk5pRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 03:30:48 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 03:30:48 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 03:30:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2wrSTncfwW/Render', 03:30:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:48 INFO - 'HOME': '/Users/cltbld', 03:30:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:48 INFO - 'LOGNAME': 'cltbld', 03:30:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:48 INFO - 'MOZ_NO_REMOTE': '1', 03:30:48 INFO - 'NO_EM_RESTART': '1', 03:30:48 INFO - 'PAGER': '/bin/cat', 03:30:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:48 INFO - 'PWD': '/builds/slave/test', 03:30:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:48 INFO - 'SHELL': '/bin/bash', 03:30:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYV5sk5pRc/Listeners', 03:30:48 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 03:30:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:48 INFO - 'USER': 'cltbld', 03:30:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:48 INFO - 'XPC_FLAGS': '0x0', 03:30:48 INFO - 'XPC_SERVICE_NAME': '0', 03:30:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:48 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 03:30:48 INFO - Cleaning up... 03:30:48 INFO - Return code: 0 03:30:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:30:48 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')]} 03:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:48 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')]} 03:30:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:48 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 0x105742a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffba85a4590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056a6d30>, '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 0x10540bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10569eb20>, '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.2wrSTncfwW/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.qYV5sk5pRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 03:30:48 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 03:30:48 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 03:30:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2wrSTncfwW/Render', 03:30:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:48 INFO - 'HOME': '/Users/cltbld', 03:30:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:48 INFO - 'LOGNAME': 'cltbld', 03:30:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:48 INFO - 'MOZ_NO_REMOTE': '1', 03:30:48 INFO - 'NO_EM_RESTART': '1', 03:30:48 INFO - 'PAGER': '/bin/cat', 03:30:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:48 INFO - 'PWD': '/builds/slave/test', 03:30:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:48 INFO - 'SHELL': '/bin/bash', 03:30:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYV5sk5pRc/Listeners', 03:30:48 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 03:30:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:48 INFO - 'USER': 'cltbld', 03:30:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:48 INFO - 'XPC_FLAGS': '0x0', 03:30:48 INFO - 'XPC_SERVICE_NAME': '0', 03:30:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:48 INFO - Downloading/unpacking psutil>=3.1.1 03:30:48 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 03:30:48 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 03:30:48 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 03:30:48 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 03:30:48 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 03:30:48 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 03:30:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:30:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:30:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:30:51 INFO - Installing collected packages: psutil 03:30:51 INFO - Running setup.py install for psutil 03:30:52 INFO - building 'psutil._psutil_osx' extension 03:30:52 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 03:30:52 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 03:30:52 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 03:30:52 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 03:30:52 INFO - building 'psutil._psutil_posix' extension 03:30:52 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 03:30:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:30:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:30:52 INFO - ^ 03:30:52 INFO - 1 warning generated. 03:30:52 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 03:30:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:30:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:30:52 INFO - Successfully installed psutil 03:30:52 INFO - Cleaning up... 03:30:52 INFO - Return code: 0 03:30:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:52 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 0x105742a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffba85a4590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056a6d30>, '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 0x10540bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10569eb20>, '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.2wrSTncfwW/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.qYV5sk5pRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 03:30:52 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 03:30:52 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 03:30:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2wrSTncfwW/Render', 03:30:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:52 INFO - 'HOME': '/Users/cltbld', 03:30:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:52 INFO - 'LOGNAME': 'cltbld', 03:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:52 INFO - 'MOZ_NO_REMOTE': '1', 03:30:52 INFO - 'NO_EM_RESTART': '1', 03:30:52 INFO - 'PAGER': '/bin/cat', 03:30:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:52 INFO - 'PWD': '/builds/slave/test', 03:30:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:52 INFO - 'SHELL': '/bin/bash', 03:30:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYV5sk5pRc/Listeners', 03:30:52 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 03:30:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:52 INFO - 'USER': 'cltbld', 03:30:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:52 INFO - 'XPC_FLAGS': '0x0', 03:30:52 INFO - 'XPC_SERVICE_NAME': '0', 03:30:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:30:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:30:55 INFO - Installing collected packages: mozsystemmonitor 03:30:55 INFO - Running setup.py install for mozsystemmonitor 03:30:55 INFO - Successfully installed mozsystemmonitor 03:30:55 INFO - Cleaning up... 03:30:55 INFO - Return code: 0 03:30:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105742a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffba85a4590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056a6d30>, '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 0x10540bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10569eb20>, '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.2wrSTncfwW/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.qYV5sk5pRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 03:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:30:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2wrSTncfwW/Render', 03:30:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:55 INFO - 'HOME': '/Users/cltbld', 03:30:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:55 INFO - 'LOGNAME': 'cltbld', 03:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:55 INFO - 'MOZ_NO_REMOTE': '1', 03:30:55 INFO - 'NO_EM_RESTART': '1', 03:30:55 INFO - 'PAGER': '/bin/cat', 03:30:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:55 INFO - 'PWD': '/builds/slave/test', 03:30:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:55 INFO - 'SHELL': '/bin/bash', 03:30:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYV5sk5pRc/Listeners', 03:30:55 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 03:30:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:55 INFO - 'USER': 'cltbld', 03:30:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:55 INFO - 'XPC_FLAGS': '0x0', 03:30:55 INFO - 'XPC_SERVICE_NAME': '0', 03:30:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:56 INFO - Downloading/unpacking jsonschema==2.5.1 03:30:56 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 03:30:56 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 03:30:56 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 03:30:56 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 03:30:56 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 03:30:56 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 03:30:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:30:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:30:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:30:59 INFO - Downloading functools32-3.2.3-2.tar.gz 03:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:30:59 INFO - warning: no files found matching '*.txt' 03:30:59 INFO - no previously-included directories found matching 'build' 03:30:59 INFO - no previously-included directories found matching 'dist' 03:30:59 INFO - no previously-included directories found matching '.git*' 03:30:59 INFO - Installing collected packages: jsonschema, functools32 03:30:59 INFO - Running setup.py install for functools32 03:30:59 INFO - warning: no files found matching '*.txt' 03:30:59 INFO - no previously-included directories found matching 'build' 03:30:59 INFO - no previously-included directories found matching 'dist' 03:30:59 INFO - no previously-included directories found matching '.git*' 03:30:59 INFO - Successfully installed jsonschema functools32 03:30:59 INFO - Cleaning up... 03:30:59 INFO - Return code: 0 03:30:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:30:59 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 0x105742a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffba85a4590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056a6d30>, '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 0x10540bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10569eb20>, '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.2wrSTncfwW/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.qYV5sk5pRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 03:30:59 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 03:30:59 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 03:30:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2wrSTncfwW/Render', 03:30:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:30:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:30:59 INFO - 'HOME': '/Users/cltbld', 03:30:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:30:59 INFO - 'LOGNAME': 'cltbld', 03:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:59 INFO - 'MOZ_NO_REMOTE': '1', 03:30:59 INFO - 'NO_EM_RESTART': '1', 03:30:59 INFO - 'PAGER': '/bin/cat', 03:30:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:59 INFO - 'PWD': '/builds/slave/test', 03:30:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:30:59 INFO - 'SHELL': '/bin/bash', 03:30:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYV5sk5pRc/Listeners', 03:30:59 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 03:30:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:30:59 INFO - 'USER': 'cltbld', 03:30:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:30:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:59 INFO - 'XPC_FLAGS': '0x0', 03:30:59 INFO - 'XPC_SERVICE_NAME': '0', 03:30:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:59 INFO - Downloading/unpacking blobuploader==1.2.4 03:30:59 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 03:30:59 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 03:30:59 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 03:30:59 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 03:30:59 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 03:30:59 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 03:31:02 INFO - Downloading blobuploader-1.2.4.tar.gz 03:31:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:31:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:31:02 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 03:31:02 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 03:31:02 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 03:31:02 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 03:31:02 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 03:31:02 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 03:31:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:31:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:31:02 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 03:31:02 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 03:31:02 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 03:31:02 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 03:31:02 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 03:31:02 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 03:31:03 INFO - Downloading docopt-0.6.1.tar.gz 03:31:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:31:03 INFO - Installing collected packages: blobuploader, requests, docopt 03:31:03 INFO - Running setup.py install for blobuploader 03:31:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:31:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:31:03 INFO - Running setup.py install for requests 03:31:04 INFO - Running setup.py install for docopt 03:31:04 INFO - Successfully installed blobuploader requests docopt 03:31:04 INFO - Cleaning up... 03:31:04 INFO - Return code: 0 03:31:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:04 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 0x105742a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffba85a4590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056a6d30>, '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 0x10540bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10569eb20>, '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.2wrSTncfwW/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.qYV5sk5pRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 03:31:04 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 03:31:04 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 03:31:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2wrSTncfwW/Render', 03:31:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:04 INFO - 'HOME': '/Users/cltbld', 03:31:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:04 INFO - 'LOGNAME': 'cltbld', 03:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:04 INFO - 'MOZ_NO_REMOTE': '1', 03:31:04 INFO - 'NO_EM_RESTART': '1', 03:31:04 INFO - 'PAGER': '/bin/cat', 03:31:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:04 INFO - 'PWD': '/builds/slave/test', 03:31:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:04 INFO - 'SHELL': '/bin/bash', 03:31:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYV5sk5pRc/Listeners', 03:31:04 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 03:31:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:04 INFO - 'USER': 'cltbld', 03:31:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:04 INFO - 'XPC_FLAGS': '0x0', 03:31:04 INFO - 'XPC_SERVICE_NAME': '0', 03:31:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:31:04 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-qHfDj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:31:04 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-5NEJuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:31:04 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-f_hoXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:31:04 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-qUFDw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:31:04 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-uTeulf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:31:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-ei01g8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:31:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-INk50U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:31:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-FJrjD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:31:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-j6xjMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:31:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-3EbXZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:31:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-mpNxr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:31:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-tdnWh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:31:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-4UXFDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:31:05 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-HBlPVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:06 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-tBnmqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:31:06 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-_hIDYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:31:06 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-rhJimH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:31:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:31:06 INFO - Running setup.py install for manifestparser 03:31:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:31:06 INFO - Running setup.py install for mozcrash 03:31:06 INFO - Running setup.py install for mozdebug 03:31:06 INFO - Running setup.py install for mozdevice 03:31:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:31:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:31:06 INFO - Running setup.py install for mozfile 03:31:07 INFO - Running setup.py install for mozhttpd 03:31:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:31:07 INFO - Running setup.py install for mozinfo 03:31:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:31:07 INFO - Running setup.py install for mozInstall 03:31:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:31:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:31:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:31:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:31:07 INFO - Running setup.py install for mozleak 03:31:07 INFO - Running setup.py install for mozlog 03:31:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:31:07 INFO - Running setup.py install for moznetwork 03:31:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:31:08 INFO - Running setup.py install for mozprocess 03:31:08 INFO - Running setup.py install for mozprofile 03:31:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:31:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:31:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:31:08 INFO - Running setup.py install for mozrunner 03:31:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:31:08 INFO - Running setup.py install for mozscreenshot 03:31:08 INFO - Running setup.py install for moztest 03:31:08 INFO - Running setup.py install for mozversion 03:31:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:31:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:31:08 INFO - Cleaning up... 03:31:08 INFO - Return code: 0 03:31:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31:08 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')]} 03:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:08 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')]} 03:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:08 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 0x105742a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffba85a4590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056a6d30>, '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 0x10540bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10569eb20>, '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.2wrSTncfwW/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.qYV5sk5pRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 03:31:08 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 03:31:08 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 03:31:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2wrSTncfwW/Render', 03:31:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:08 INFO - 'HOME': '/Users/cltbld', 03:31:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:08 INFO - 'LOGNAME': 'cltbld', 03:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:08 INFO - 'MOZ_NO_REMOTE': '1', 03:31:08 INFO - 'NO_EM_RESTART': '1', 03:31:08 INFO - 'PAGER': '/bin/cat', 03:31:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:08 INFO - 'PWD': '/builds/slave/test', 03:31:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:08 INFO - 'SHELL': '/bin/bash', 03:31:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYV5sk5pRc/Listeners', 03:31:08 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 03:31:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:08 INFO - 'USER': 'cltbld', 03:31:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:08 INFO - 'XPC_FLAGS': '0x0', 03:31:08 INFO - 'XPC_SERVICE_NAME': '0', 03:31:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:31:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-n58Prq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:31:09 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)) 03:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:31:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-IWLE25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:31:09 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)) 03:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:31:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-yKWKHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:31:09 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)) 03:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:31:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-AnURys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:31:09 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)) 03:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:31:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-ovdhVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:31:09 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)) 03:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:31:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-agTNWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:31:09 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)) 03:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:31:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-kigpi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:31:09 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)) 03:31:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:31:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Ybp6jp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:31:10 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)) 03:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:31:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-ltuqin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:31:10 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)) 03:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:31:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-2PZxig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:31:10 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)) 03:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:31:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-IiZTQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:31:10 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)) 03:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:31:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-5t4zj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:31:10 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)) 03:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:31:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-PoHpM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:31:10 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)) 03:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:31:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-P1Z1De-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:31:10 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)) 03:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-mfOteO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:10 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)) 03:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:31:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-JPXCzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:31:10 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)) 03:31:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:31:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-JxWME5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:31:11 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)) 03:31:11 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)) 03:31:11 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)) 03:31:11 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)) 03:31:11 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)) 03:31:11 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)) 03:31:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:31:11 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 03:31:11 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 03:31:11 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 03:31:11 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 03:31:11 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 03:31:11 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 03:31:13 INFO - Downloading blessings-1.6.tar.gz 03:31:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:31:13 INFO - Installing collected packages: blessings 03:31:13 INFO - Running setup.py install for blessings 03:31:13 INFO - Successfully installed blessings 03:31:13 INFO - Cleaning up... 03:31:13 INFO - Return code: 0 03:31:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:31:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:31:14 INFO - Reading from file tmpfile_stdout 03:31:14 INFO - Current package versions: 03:31:14 INFO - blessings == 1.6 03:31:14 INFO - blobuploader == 1.2.4 03:31:14 INFO - docopt == 0.6.1 03:31:14 INFO - functools32 == 3.2.3-2 03:31:14 INFO - jsonschema == 2.5.1 03:31:14 INFO - manifestparser == 1.1 03:31:14 INFO - mozInstall == 1.12 03:31:14 INFO - mozcrash == 0.17 03:31:14 INFO - mozdebug == 0.1 03:31:14 INFO - mozdevice == 0.48 03:31:14 INFO - mozfile == 1.2 03:31:14 INFO - mozhttpd == 0.7 03:31:14 INFO - mozinfo == 0.9 03:31:14 INFO - mozleak == 0.1 03:31:14 INFO - mozlog == 3.3 03:31:14 INFO - moznetwork == 0.27 03:31:14 INFO - mozprocess == 0.23 03:31:14 INFO - mozprofile == 0.28 03:31:14 INFO - mozrunner == 6.12 03:31:14 INFO - mozscreenshot == 0.1 03:31:14 INFO - mozsystemmonitor == 0.3 03:31:14 INFO - moztest == 0.7 03:31:14 INFO - mozversion == 1.4 03:31:14 INFO - psutil == 3.1.1 03:31:14 INFO - requests == 1.2.3 03:31:14 INFO - wsgiref == 0.1.2 03:31:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:14 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 0x105742a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffba85a4590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056a6d30>, '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 0x10540bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10569eb20>, '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.2wrSTncfwW/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.qYV5sk5pRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 03:31:14 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 03:31:14 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 03:31:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2wrSTncfwW/Render', 03:31:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:14 INFO - 'HOME': '/Users/cltbld', 03:31:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:14 INFO - 'LOGNAME': 'cltbld', 03:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:14 INFO - 'MOZ_NO_REMOTE': '1', 03:31:14 INFO - 'NO_EM_RESTART': '1', 03:31:14 INFO - 'PAGER': '/bin/cat', 03:31:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:14 INFO - 'PWD': '/builds/slave/test', 03:31:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:14 INFO - 'SHELL': '/bin/bash', 03:31:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYV5sk5pRc/Listeners', 03:31:14 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 03:31:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:14 INFO - 'USER': 'cltbld', 03:31:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:14 INFO - 'XPC_FLAGS': '0x0', 03:31:14 INFO - 'XPC_SERVICE_NAME': '0', 03:31:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:14 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)) 03:31:14 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)) 03:31:14 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)) 03:31:14 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)) 03:31:14 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)) 03:31:14 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)) 03:31:14 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)) 03:31:14 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)) 03:31:14 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)) 03:31:14 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)) 03:31:14 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)) 03:31:14 INFO - Cleaning up... 03:31:14 INFO - Return code: 0 03:31:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:14 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 0x105742a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffba85a4590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056a6d30>, '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 0x10540bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10569eb20>, '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.2wrSTncfwW/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.qYV5sk5pRc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 03:31:14 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 03:31:14 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 03:31:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2wrSTncfwW/Render', 03:31:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:14 INFO - 'HOME': '/Users/cltbld', 03:31:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:14 INFO - 'LOGNAME': 'cltbld', 03:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:14 INFO - 'MOZ_NO_REMOTE': '1', 03:31:14 INFO - 'NO_EM_RESTART': '1', 03:31:14 INFO - 'PAGER': '/bin/cat', 03:31:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:14 INFO - 'PWD': '/builds/slave/test', 03:31:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:14 INFO - 'SHELL': '/bin/bash', 03:31:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYV5sk5pRc/Listeners', 03:31:14 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 03:31:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:14 INFO - 'USER': 'cltbld', 03:31:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:14 INFO - 'XPC_FLAGS': '0x0', 03:31:14 INFO - 'XPC_SERVICE_NAME': '0', 03:31:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:31:14 INFO - Cleaning up... 03:31:14 INFO - Return code: 0 03:31:14 INFO - Running post-action listener: _resource_record_post_action 03:31:14 INFO - Running post-action listener: _start_resource_monitoring 03:31:14 INFO - Starting resource monitoring. 03:31:14 INFO - [mozharness: 2016-07-27 10:31:14.693379Z] Finished create-virtualenv step (success) 03:31:14 INFO - [mozharness: 2016-07-27 10:31:14.694356Z] Running install step. 03:31:14 INFO - Running pre-action listener: _resource_record_pre_action 03:31:14 INFO - Running main action method: install 03:31:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:31:14 INFO - Reading from file tmpfile_stdout 03:31:14 INFO - Detecting whether we're running mozinstall >=1.0... 03:31:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:31:15 INFO - Reading from file tmpfile_stdout 03:31:15 INFO - Output received: 03:31:15 INFO - Usage: mozinstall [options] installer 03:31:15 INFO - Options: 03:31:15 INFO - -h, --help show this help message and exit 03:31:15 INFO - -d DEST, --destination=DEST 03:31:15 INFO - Directory to install application into. [default: 03:31:15 INFO - "/builds/slave/test"] 03:31:15 INFO - --app=APP Application being installed. [default: firefox] 03:31:15 INFO - mkdir: /builds/slave/test/build/application 03:31:15 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'] 03:31:15 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 03:31:49 INFO - Reading from file tmpfile_stdout 03:31:49 INFO - Output received: 03:31:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:31:49 INFO - Running post-action listener: _resource_record_post_action 03:31:49 INFO - [mozharness: 2016-07-27 10:31:49.860200Z] Finished install step (success) 03:31:49 INFO - [mozharness: 2016-07-27 10:31:49.860366Z] Running run-tests step. 03:31:49 INFO - Running pre-action listener: _resource_record_pre_action 03:31:49 INFO - Running main action method: run_tests 03:31:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:31:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:31:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:31:49 INFO - 2016-07-27 03:31:49.875 screenresolution[1931:17453] starting screenresolution argv=screenresolution get 03:31:49 INFO - 2016-07-27 03:31:49.877 screenresolution[1931:17453] Display 0: 1600x1200x32@50 03:31:49 INFO - 2016-07-27 03:31:49.883 screenresolution[1932:17456] starting screenresolution argv=screenresolution list 03:31:49 INFO - Available Modes on Display 0 03:31:49 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 03:31:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:31:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:31:49 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 03:31:49 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 03:31:50 INFO - Graphics/Displays: 03:31:50 INFO - Intel Iris: 03:31:50 INFO - Chipset Model: Intel Iris 03:31:50 INFO - Type: GPU 03:31:50 INFO - Bus: Built-In 03:31:50 INFO - VRAM (Dynamic, Max): 1536 MB 03:31:50 INFO - Vendor: Intel (0x8086) 03:31:50 INFO - Device ID: 0x0a2e 03:31:50 INFO - Revision ID: 0x0009 03:31:50 INFO - Displays: 03:31:50 INFO - Display: 03:31:50 INFO - Resolution: 1600 x 1200 @ 50 Hz 03:31:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:31:50 INFO - Display Serial Number: 1600x1200 50 03:31:50 INFO - Main Display: Yes 03:31:50 INFO - Mirror: Off 03:31:50 INFO - Online: Yes 03:31:50 INFO - Rotation: Supported 03:31:50 INFO - Return code: 0 03:31:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:31:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:31:50 INFO - Python 2.7.3 03:31:50 INFO - Return code: 0 03:31:50 INFO - grabbing minidump binary from tooltool 03:31:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:50 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 0x1056a6d30>, '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 0x10540bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10569eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:31:50 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 03:31:50 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 03:31:50 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 03:31:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:31:50 INFO - Return code: 0 03:31:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:31:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:31:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:31:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:31:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:31:50 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/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0011', '--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 03:31:50 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/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0011 --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 03:31:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2wrSTncfwW/Render', 03:31:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:50 INFO - 'HOME': '/Users/cltbld', 03:31:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:50 INFO - 'LOGNAME': 'cltbld', 03:31:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:31:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:31:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:50 INFO - 'MOZ_NO_REMOTE': '1', 03:31:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:31:50 INFO - 'NO_EM_RESTART': '1', 03:31:50 INFO - 'PAGER': '/bin/cat', 03:31:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:50 INFO - 'PWD': '/builds/slave/test', 03:31:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:31:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:50 INFO - 'SHELL': '/bin/bash', 03:31:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qYV5sk5pRc/Listeners', 03:31:50 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 03:31:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:50 INFO - 'USER': 'cltbld', 03:31:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:50 INFO - 'XPC_FLAGS': '0x0', 03:31:50 INFO - 'XPC_SERVICE_NAME': '0', 03:31:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:50 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/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0011', '--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 03:31:50 INFO - mozversion application_buildid: 20160721093519 03:31:50 INFO - mozversion application_changeset: 67186c2d647ed2807eacc51cc834ca7932d38fba 03:31:50 INFO - mozversion application_display_name: Nightly 03:31:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:31:50 INFO - mozversion application_name: Firefox 03:31:50 INFO - mozversion application_remotingname: firefox 03:31:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 03:31:50 INFO - mozversion application_vendor: Mozilla 03:31:50 INFO - mozversion application_version: 50.0a1 03:31:50 INFO - mozversion platform_buildid: 20160721093519 03:31:50 INFO - mozversion platform_changeset: 67186c2d647ed2807eacc51cc834ca7932d38fba 03:31:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 03:31:50 INFO - mozversion platform_version: 50.0a1 03:31:50 INFO - using testdate: 1469615510 03:31:50 INFO - actual date: 1469615510 03:31:50 INFO - starting webserver on 'localhost:56045' 03:31:50 INFO - SUITE-START | Running 2 tests 03:31:50 INFO - TEST-START | dromaeo_css 03:31:50 INFO - operating with platform_type : mac_ 03:31:50 INFO - Initialising browser for dromaeo_css test... 03:31:50 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmpOon5Jw/profile http://localhost:56045/getInfo.html) 03:31:52 INFO - PROCESS | 1942 | JavaScript error: , line 0: uncaught exception: undefined 03:31:52 INFO - PROCESS | 1942 | __metrics Screen width/height:1600/1200 03:31:52 INFO - PROCESS | 1942 | colorDepth:24 03:31:52 INFO - PROCESS | 1942 | Browser inner width/height: 1024/689 03:31:52 INFO - PROCESS | 1942 | __metrics 03:31:52 INFO - PROCESS | 1942 | 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" 03:31:52 INFO - PROCESS | 1942 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:31:53 INFO - TEST-INFO | 1942: exit 0 03:31:53 INFO - Browser initialized. 03:31:53 INFO - Running cycle 1/1 for dromaeo_css test... 03:31:53 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmpOon5Jw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:32:05 INFO - PROCESS | 1944 | RSS: Main: 245370880 03:32:05 INFO - PROCESS | 1944 | 03:32:57 INFO - PROCESS | 1944 | 1469615577183 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] 03:35:08 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:56045/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56045/tests/dromaeo/cssquery-ext.html) 03:35:10 INFO - PROCESS | 1944 | RSS: Main: 358596608 03:35:10 INFO - PROCESS | 1944 | 03:38:13 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:56045/tests/dromaeo/cssquery-ext.html (next: http://localhost:56045/tests/dromaeo/cssquery-jquery.html) 03:38:14 INFO - PROCESS | 1944 | RSS: Main: 386809856 03:38:14 INFO - PROCESS | 1944 | 03:41:16 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:56045/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56045/tests/dromaeo/cssquery-mootools.html) 03:41:17 INFO - PROCESS | 1944 | RSS: Main: 377368576 03:41:17 INFO - PROCESS | 1944 | 03:44:19 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:56045/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56045/tests/dromaeo/cssquery-prototype.html) 03:44:20 INFO - PROCESS | 1944 | RSS: Main: 378413056 03:44:20 INFO - PROCESS | 1944 | 03:47:23 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:56045/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56045/tests/dromaeo/cssquery-yui.html) 03:47:24 INFO - PROCESS | 1944 | RSS: Main: 346288128 03:47:24 INFO - PROCESS | 1944 | 03:50:27 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:56045/tests/dromaeo/cssquery-yui.html (next: http://localhost:56045/tests/dromaeo/cssquery-dojo.html) 03:50:27 INFO - PROCESS | 1944 | RSS: Main: 421158912 03:50:27 INFO - PROCESS | 1944 | 03:50:27 INFO - PROCESS | 1944 | __start_tp_report 03:50:27 INFO - PROCESS | 1944 | _x_x_mozilla_page_load 03:50:27 INFO - PROCESS | 1944 | _x_x_mozilla_page_load_details 03:50:27 INFO - PROCESS | 1944 | |i|pagename|runs| 03:50:27 INFO - PROCESS | 1944 | |0;dojo.html;3338.5993680758297;3357.8153201573914;3373.122988023114;3384.999999999997;3413.0102270341604;5590.468905453975;5681.744321505532;5700.37295897451;5761.366249712528;5775.855603609902;5745.540356771458;5754.827355179341;5764.164196191539;5900.2919649642;5924.111383292508;5113.616478764089;5192.299039629658;5284.339457567804;5303.628745987782;5331.600129990266;5289.259503669489;5338.092524270864;5339.759710813023;5341.092014357569;5362.088444964346;4332.761698106603;5099.107656160176;5126.5898728101665;5141.820036298749;5204.505571970694;5157.5616072633875;5188.5849132069725;5189.299444574972;5194.896102077975;5196.558292545138;5144.3312369392115;5157.974210128963;5197.5322220999915;5227.163653815372;5287.920681189786;5682.147677848315;5710.857228569277;5738.167965645;5779.075347944308;5798.362180160179;6235.594686345418;6240.00159974402;6295.2760432550385;6334.556581039329;6340.3659634036685;6121.056693972082;6218.906716399258;6256.155419018465;6345;6374.362563743635;5815.534757219412;5822.82531524055;5826.854328641775;5827.184194212771;5865.912011319833;6273.592216505913;6276;6290.685465726695;6291.307956124822;6303.684815759194;6038.215032045894;6080.269881430733;6125.9892117800955;6128.540359472968;6163.96918015416;6295.622262664255;6317.052442133717;6327.588706734047;6327.683615819283;6340.492760579143;6256.593321434092;6261.467775239155;6271.560990730605;6272.655003974825;6277.7175027123885;6100.1270446244;6190.50475961922;6224.968875155686;6236.126942228092;6306.211723534559;4906.975465122723;4924.089043526959;4939.8271060513425;4970.130227210296;5002.374703162105;4432.468103827497;4444.555519456189;4446.688731788744;4451.043025749479;4505.752183629932;5733.312002559636;5762.164486149485;5765.250517432707;5766.221560089418;5864.8533786654825;6130.826205142237;6205.03821907605;6244.250689917239;6257.061440783918;6326.019466982625;5748.367679555246;5769.85575360611;5783.306003279549;5810.924978878921;5824.068149096209;4646.605038571692;5196.272521846944;5434.755436005389;5448.291722076184;5464.644798088111;6150.507959363151;6284.748610055546;6307.96846015767;6333.398327158912;6340.048992651139;6224.906626400517;6273.3099359071375;6289.622622642473;6318.115463835159;6351.364863513611;6297.2128483939505;6316.210473690789;6328;6329.936700632935;6344.71448784815;6062.181605483203;6218.968905155445;6224.346443623354;6236.937630623635;6239.656818874824;6253.468455181361;6266.780662676967;6271.686415679289;6335.619862808247;6349.523785715997;6140.109684095871;6154.538409619386;6155.569110162246;6164.630122192682;6181.350958149509;5921.407859214044;6142.662153581417;6153.6923153841235;6161.137440758387;6171.043488259327;4918.975405122951;5913.763449461973;5926.140709597171;5958.880822383441;6021.638701677738;6111.174991376285;6111.537809342371;6153.077038444269;6158.938410615837;6191.19514463117;5776.162456443708;5866.67733274674;5980.282366116094;5991.310999234969;6023.096535519707 03:50:27 INFO - PROCESS | 1944 | |1;ext.html;458.6972598085263;465.2625588442319;492.21983156696655;509.24377299709926;513.8869448721281;27171.32071698206;32591.837040814793;41290;42403.78798106005;42690.359644605356;43796.56203437973;44195;44237.557624423745;44414.77792611033;44421.77789111058;9766.365186262905;9945.950270248639;9949.005099490028;9955.452450115241;9959;8800;8953.194212520872;11995.22031067982;12165.878341216607;12186.14696971208;12178.878211217907;12222.594401643835;12249.265044097383;12255.509779608805;12283.570075047375;8769;12104.81842772359;12175.208611440228;12191;12203.328816915111;11661.475233614508;11897.917864373787;11908.16642835002;12161.574344897974;12203.816942745865;41533;41879.58120418818;41919.7904010478;42131.1573768526;42141;3257.843465569736;3365.444701624229;3554.662307080823;3683.907902302437;3814.275287695357;30998.845005775052;31116;31359.529607055912;32300.67699323;33029.17427064318;3219.645838957713;3261.59230096238;3277.5411442397844;3294.2093897464674;3420.675035871588;530272;535199;538695;540081.9959002035;541819.999999996;62441;62848.371516285166;62917.37082629207;62941.68529157416;62975.6851215741;35334;35804.92585222448;35853.282934341165;37439.812800936365;37451.250974980896;16449.25978330978;16566.337346506247;16625;16625.251863666163;16672.999620022838;11572.36352000648;11722.296662200295;12651;12768.297743624189;12785.296808675426;17396.95618262908;17530.211140498708;19251.147657010137;20503.794962050488;20534.281300154424;7891.368690504746;7924.564148971862;7950.363970882316;7952.36381089523;7981.725208315718;887.4143065576689;964.3249725192394;1034.2036631793476;1046.8691413573304;1048.2871647279771;30503.389932201233;30593;30703;31221.8438907804;31277.687223127934;1076.101455284846;1120.3166068698088;1159.4376727287427;1186.691460220349;1239.4360565942475;15803.36786528526;15885.887987840972;15913.283902224197;16080.43718469848;16314;740466;743549;744368;749662;750585;59952.40047599556;60618;60621.69689151526;60637;60690.0896486553;1268.2010333490027;1301.1087405127707;1305.738852229539;1399.55214331415;1418.7729963205838;31175.37649246957;32877.01368958935;33652;33802;33825.15437114092;58107.4189258102;59451.108233377396;62335.688321558104;62342.688286558274;62371;659945;669840;671399.6430018015;671571;672416;45745.54254457413;46748.7662561685;46796.7660161697;47503.52496474991;47604.7619761899;50597.49402505928;50672;50860;51771.964560709326;52282.47717522928;41126.79436602798;41242.58757412388;41927.790361048;42211.78894105633;43348.56651433445;35318;36159.27681446409;40360.3945940817;40858.1399302046;40922.703116173565;40438.59561404466;40787.79606101951;40913;41152.794236028625;41256.79371603243;30823.2294192647;35609.1097722559;36054.8197259012;37024.81487592545;37179;24820.00719971181;24938.875305623355;26683;27140.810550434097;27343.316417089733 03:50:27 INFO - PROCESS | 1944 | |2;jquery.html;352.1618547856102;372.70928675633;375.8027035806203;376.26440309195516;404.2783631218274;12402.255864648127;16621.66756664866;20359.491012724688;20982.58034839302;21076.789232107694;21359.57280854382;21369.465763355925;21758.200037965453;21862.45343866404;21976.34070977873;5290.576753859701;5755.194272801811;5858.502027327671;6041.6375017499095;6130.172426722403;5811.941880581182;5952.970235148819;5988.8502787430225;5994.700264986755;6017.699115044252;4150.3359462486005;4388.056567837914;5872.882542349151;6633.867322653568;6716.529842910998;6360.840978975516;6690.330966903295;6690.83272918176;6705.396514313687;6713.227978782429;4781.497942716;5620.775168993236;5655;5666.943330566724;5670.8015219467125;26141.869290653423;26711.73288267112;26762.866185669143;26864.59703104455;26921.596176057374;2280.7605201453775;2485.77567341742;2671.9846458345896;2731.934545527246;3092.4279008383296;16212.351505939749;16218;16361.263743131587;16698.332066717318;16714.916425417796;1878.6473738907964;1899.259288877339;1909.713542968552;1920.836728878047;1943.9416817495498;120616;129642;130014.3499282507;132580.33709831486;133282;9272.211861991671;10547.630832920964;10716.892831071746;10856.34861908288;11071.225014248925;13417.932910335387;13467.797983030263;13521.932390338183;13549.390277437538;13550.390232439564;8415.495070295801;8512.914870851213;8520.446170998865;8542.701005464904;8636.611352489153;8612.8708069379;8616.310695144373;8647.535452396163;8789.34079943994;8850.513221772864;5615.820677657738;6499.8625240582705;6564.245111812107;6584.209894812653;6589.604623722592;1576.393088660851;1814.7550080738931;1843.8617103717274;1858.2659849359698;1862.1527205121638;921.6820197032014;962.1629182611173;966.3042609321277;983.6999715086968;1021.6526381030485;16963;21131.04910279041;21271.255506057212;22244.55510889773;22559;924.8242833861544;942.236788201559;946.9005754395689;950.5912457643279;1017.0998666180484;11320.150988675718;11708.515962765869;11761.823572646417;11944.343061131547;11955.282683039046;613469;627367;629507;638665;644370;142202;148441;150410.24794875956;152851;153341.2332938328;981.3817295104038;1011.2365164300905;1052.7841792432532;1058.0266155137292;1059.5602824827765;19015.52461188481;19899.502512437306;19952.700709489658;19962.30131945375;20029.198832046553;54745.452545474036;56076;56462.717686411306;57297.140542892725;57422.42577574171;66277;80708;80901.19098808937;82062;83038.3392332162;7975.1227364988645;8082.4746391486715;8108.270255677017;8143.714969976022;8228.300594449538;11544;12613.369331533204;12619.30593817349;12955.805662915252;13235;5497.367802702581;5754.7122643866805;10998.450077496253;11405.144614153805;11478.88521114778;10863.994369241038;10987.066099381642;11009.61466348674;11380.203385762918;11425.828612570653;11409.429528523706;11577.421128943688;11613.187076904536;11632.941835290769;11693.356865372487;7857.319791924697;7935.523868567904;8087.312578430664;8281.710140145307;8309.293710034473;5603.103503439593;8505.766663833832;9090;10880.919480597298;11150.888491114985 03:50:27 INFO - PROCESS | 1944 | |3;mootools.html;1370.9245991470477;1525.001124263607;1580.5574439157037;1795.416489640867;1954.1304119666745;7074.68163932623;7747.535147891131;8275.08974012859;8658.610362533685;8873.378863479558;8192.959035204816;8742.86885696715;8841.336899732514;8856.247218986378;8857.95571022144;2220.844540882139;2392.342105920877;2392.35406440261;2401.4236583220077;2410.517896420714;1993.6610776168086;2301.4476525633813;2364.13708996216;2396.676448679423;2406.2300063979533;2350.8471949323325;2377.8335516513853;2380.5833979053596;2391.3184742348435;2397.3886658901956;2341.2625023117835;2365.361352434833;2367.75138610446;2381.2499062795164;2385.1294277588727;2328.2087524324706;2353.623420252751;2355;2359.4691194481106;2360.5042940982416;7849.843003139905;8901.554922253927;8901.910980890172;8912.108789121101;8936.151065648754;5219.530242278213;5235.450277720823;5413.431589683066;5421.783128674848;5428.67427954324;3570.9107272318142;3656;3658.798766067892;3659.8170091495317;3667.3765459871884;948.8481842905171;1070.1213735052684;1075.1936047964027;1086.7772106718103;1101.9228653994223;11544.769104617944;11660.358680272582;11678.941605292004;11681.415929203506;11688.006519445942;3881.2819628368843;4424.867253982386;4442.977785111054;4593.770311484413;4614.723116613014;9156.954215228881;9157.313201509913;9171.90828091724;9185.265178785681;9214.723558293263;5232.843014709565;5241.082810508222;5247.2129180623215;5252.684838909678;5254.290670759475;4413.955860441419;4556.339330797082;4810.13417584838;4898.4121905371585;4925.827596034122;3617.5297211362354;3646.5385864541327;3711.870084547082;3805.124821291063;3836.7506112102624;1136.8578927634046;1147.2198904744687;1161.1697636189995;1161.9477123529462;1167.8656954450178;841.852675781748;914.3827916156567;939.3330735177964;973.2165606686543;985.6621921047732;5626.324841019022;5847.883042339129;5874;6074.331823499411;6738.629375384401;420.7938110325918;443.24205608409983;448.7487007275936;455.3397573518412;459.5519368615573;3954.268460334847;3997.980010099931;4055.797210139481;4071.9796401017807;4118.19695159441;9961.601535938482;10610.20423468243;10800;10807.513661885234;10839.512221950032;10284.434356110336;10660.360378377321;10700.518476668569;10706.73233169177;10744.623938162284;7963.0046244219475;8351.331893448509;8395.664173433117;8403;8412.8317433651;6009.579329447071;6035.668038257938;6135.631862088289;6160.44555989963;6182;9617;10035.448050357008;10066.345687530287;10068.84896726535;10077.14344280715;10187.84718229242;10260.435676037889;10270.127039201452;10344.741381465523;10362.481875906024;6274.745050989729;6957.304269573205;7255.310745479171;7479.88780168287;7711.228877112469;8383.622736976973;8412.200840920103;8514.61684224199;8557.61490732906;8581.828363432822;7543.47195696296;7648.34989025939;7658.923410765821;7706.229377062249;7834.216578342121;7366.926330736838;7419.9258007421395;7430.108386993596;7431.108267007994;7508.0990281166605;7061.788146355618;7087.751928682472;7147.428205743632;7161.713531458683;7164.104486939133;4860.198067318853;4983.75081245929;5015.473375295542;5066.746662666926;5223.947760522498;4990.126727822689;5009.148444764326;5037.672551284155;5216.452272511484;5334.439883812144 03:50:27 INFO - PROCESS | 1944 | |4;prototype.html;296.62180719582517;297.3621581707238;310.24919694339695;314.3006809848087;316.9762267829913;5200.595839123434;5257.92113118303;5264.420673797865;5290.624437646211;5298.96670149321;4550.658700597452;4658.97670511647;4941.703497790135;5230.189320655304;5291.3650361956525;4030.6976976726714;4062.796860156995;4069.69477289203;4082.9795851020704;4118.917621647565;4020.8793736187813;4093.7953102344914;4094.529129150141;4095.1195492969005;4096.713230073896;3355.9664403355896;3544.0962554548555;3695.6489133532405;3789.602091780365;4026.899327516821;3901.4342920276545;4025.657819085381;4033.858814941463;4056.655184309337;4070.5318888327924;3512.102515424759;3514.824258787076;3516.5252690886914;3537.2571759930447;3541.1678255609913;4772.618190544783;5109.875827318021;5113;5149.48505149482;5217.947820521784;3804.9429258561145;4406.325596273427;4548.385967894325;4556.589906908394;4572.314152877062;5219.817306394296;5467.371252305996;5499.285092937933;5518.33779946405;5526.613137080406;3361.428557145291;3369.1240277527772;3484.930301393958;3772.4529943158363;3822.4839646647624;4929.556339929388;5351.732413379315;5793.478586927196;5811.767529298823;5843.941560584382;3221.8066915985114;3800.847966081326;3826.5408151022057;3832.1222600925344;3834.1373191031794;3874.7287689862;3900.1224724437343;3910.335243008695;3915.7288772511874;3931.8820435386983;4112.876613701594;4169.60388763067;4524.841630542981;4534.229181039232;4556.567126123013;5289.127293996447;5482.2873026506295;5512.5314348280845;5518.586106042063;5544.306961629796;1091.67795500326;1203.4223572684984;1223.4861358229564;1332.160738734591;1337.3246510512129;806.5443024691148;823.2755175445569;843.5023336231653;866.4317585170861;880.9868651051343;5006.799728010911;5009.44896061433;5014.918808694351;5038.1435156022835;5088.354055890916;4482;5483;5499.285092937893;5512.283403157564;5528.226048353234;4221.472315960505;4694.88262793426;4980.452150263468;5021.271915572222;5027.4721154279005;2367.8105751539833;2941.4410362507906;2967.2730181105335;2978.687237840039;2980.970190298069;5046.066477701564;5761.337446193741;5767.971160144172;5768.596198266081;5873.853153671193;5452.36429071285;5588;5748.281464816898;5766.711664416763;5774.249347584787;5289.338832645919;5305.522502974751;5785.768569257267;5811.360750317462;5832.591718579743;4876.707397556158;5534.225208470737;5540.1135818270495;5631.605787594829;5693.971530142323;4384.649228061684;5190.429052804264;5467.644603100785;5470.72646367688;5499.7250137492165;5117.874067705099;5120.974395128001;5447.319085114361;5666;5711.543556393093;3519.894403167909;3657.579378371521;3749.3063783200196;3773.3019391412677;3791.658750712449;5547.861303467284;5632.88734225305;5636.633618814928;5654.519365853948;5667.202974007342;4817.927731084106;5465.699386533779;5478.671279723229;5529.944700552943;5538.510738234886;4646.8141274348645;5081.517255860687;5422.66825265124;5502.477264659851;5610.615488417325;4482.731036137842;5158.97420512895;5325.840224793263;5334.439883812144;5380.677159370451;5341.118715411913;5436.945630543644;5511.035568775528;5528;5530.253415788817;4498;4733.9053218936115;5339.4660533947845;5378.247045413724;5415.620906536505 03:50:27 INFO - PROCESS | 1944 | |5;yui.html;703.2721133626696;750.849830033993;792.04162962815;843.493903657805;844.8690452979788;15599.454019109333;16288;16306.755398669015;16308.021518708885;16316.021038737685;14614.196219207966;14765.557033288991;14773.630659233524;14787.260636968162;14883.627909302273;712.5119293284091;738.0073800737999;893.6246776353947;898.8921329440476;927.7634203278154;900.4327273817519;908.4957848394139;915.6337465013982;916.6974898283551;918.9862152067691;971.6939164163266;973.6543527047902;974.8099120671471;975.2246963663904;975.9658411955583;744.8510297940379;926.1109335038371;968.9321747477644;970;970.1995853420921;718.2422544215902;720.5784615999646;722.869883420984;723.2405973727565;723.5622448418684;15244.923775381052;15290.923545382202;15299.388024479007;15322.770158447633;15352.692946141015;13986.16083035011;14148.363323650461;14272.215028173447;14412.63968400788;14514.201718905457;13190.736185276337;13333.733325333536;13507.932460337734;13538;13856.584302470841;4584.4498660161;4651.837185698518;4703.647226457995;4719.5044520325555;4886.97373551551;123768.1434778479;125874.37062814718;126125.36937315346;127108.72891271062;128459;11173.385463799406;11292.661220163409;11316.377599231957;11324.547018119347;11725.941370293094;23221.7677823223;23235.651465228035;23237.954292056897;23245.41886452852;23268.883655581612;7968.163342848918;8062.55655938929;8075.011477274911;8161.63272652732;8315.168483151758;4401.361802538678;4472.217361961709;4557.043020965568;4643.628509719214;4675.13510000651;3450.413429716954;3551.076720052805;3570.8750193743613;3573.6069032406413;3587.9282414351565;635.4090695653088;639.9424051835357;640.9775657851952;661.6460193796296;666.7133132752963;617.8270084376381;622.707327556048;626.9937300627026;644.5810223354857;652.5334385913999;7943.324817390471;9073.12167362847;9274.287498946544;9307.454345873746;9475.28935329853;622.651315263454;640.3596403596404;643.1960049937578;646.9454788694399;649.9447546958467;3803.54357477099;4528.773561321921;4552.453705555355;4557.111363284128;4622.930656040162;96188;126415.36792316164;131994.34002829925;164251.07246782616;164389.17805411137;138001.3099934494;140968.5903140976;141076;141157;145200;104048;105412;106695;108430.45784771183;109894.35158472629;8536.70121545743;8726.65093396257;8782.868256976279;8810.163034511712;8953.641854325753;65180;66072.66963665151;66131;69013.65493172502;70014;70738.58522829412;78882;81466;89318.66022009806;97000;10886.61896833607;13694.315284235548;13721.451141954609;13752;14439.92780036093;15439.536813895602;15866.444674436802;15884.682306353578;16392.344306227616;16419.58951026234;14295.64260893486;14416.783748243575;14471.855281447471;14603.675478288767;14866.85133148698;13982.930085349508;14235.288235587972;14573.271336433349;14614.780778288121;14791.088825497503;13886.722265554834;14106.2946852659;14110.012299138962;14501.709965800414;14581.781273281122;11557.942210288895;11571.594994175164;11731.885470880252;11860.703482413008;11988.76022479528;8872.201501864864;10838.211695070226;11444.02725768319;11519.76960460803;11846.229995050295 03:50:27 INFO - PROCESS | 1944 | __end_tp_report 03:50:27 INFO - PROCESS | 1944 | __start_cc_report 03:50:27 INFO - PROCESS | 1944 | _x_x_mozilla_cycle_collect,1402 03:50:27 INFO - PROCESS | 1944 | __end_cc_report 03:50:27 INFO - PROCESS | 1944 | __startTimestamp1469616627680__endTimestamp 03:50:27 INFO - PROCESS | 1944 | 03:50:27 INFO - PROCESS | 1944 | ------- Summary: start ------- 03:50:27 INFO - PROCESS | 1944 | Number of tests: 6 03:50:27 INFO - PROCESS | 1944 | 03:50:27 INFO - PROCESS | 1944 | [#0] dojo.html Cycles:165 Average:5760.12 Median:5980.28 stddev:658.71 (11.0%) stddev-sans-first:632.75 03:50:27 INFO - PROCESS | 1944 | Values: 3338.6 3357.8 3373.1 3385.0 3413.0 5590.5 5681.7 5700.4 5761.4 5775.9 5745.5 5754.8 5764.2 5900.3 5924.1 5113.6 5192.3 5284.3 5303.6 5331.6 5289.3 5338.1 5339.8 5341.1 5362.1 4332.8 5099.1 5126.6 5141.8 5204.5 5157.6 5188.6 5189.3 5194.9 5196.6 5144.3 5158.0 5197.5 5227.2 5287.9 5682.1 5710.9 5738.2 5779.1 5798.4 6235.6 6240.0 6295.3 6334.6 6340.4 6121.1 6218.9 6256.2 6345.0 6374.4 5815.5 5822.8 5826.9 5827.2 5865.9 6273.6 6276.0 6290.7 6291.3 6303.7 6038.2 6080.3 6126.0 6128.5 6164.0 6295.6 6317.1 6327.6 6327.7 6340.5 6256.6 6261.5 6271.6 6272.7 6277.7 6100.1 6190.5 6225.0 6236.1 6306.2 4907.0 4924.1 4939.8 4970.1 5002.4 4432.5 4444.6 4446.7 4451.0 4505.8 5733.3 5762.2 5765.3 5766.2 5864.9 6130.8 6205.0 6244.3 6257.1 6326.0 5748.4 5769.9 5783.3 5810.9 5824.1 4646.6 5196.3 5434.8 5448.3 5464.6 6150.5 6284.7 6308.0 6333.4 6340.0 6224.9 6273.3 6289.6 6318.1 6351.4 6297.2 6316.2 6328.0 6329.9 6344.7 6062.2 6219.0 6224.3 6236.9 6239.7 6253.5 6266.8 6271.7 6335.6 6349.5 6140.1 6154.5 6155.6 6164.6 6181.4 5921.4 6142.7 6153.7 6161.1 6171.0 4919.0 5913.8 5926.1 5958.9 6021.6 6111.2 6111.5 6153.1 6158.9 6191.2 5776.2 5866.7 5980.3 5991.3 6023.1 03:50:27 INFO - PROCESS | 1944 | 03:50:27 INFO - PROCESS | 1944 | [#1] ext.html Cycles:180 Average:78111.23 Median:37309.41 stddev:175849.56 (471.3%) stddev-sans-first:176246.21 03:50:27 INFO - PROCESS | 1944 | Values: 458.7 465.3 492.2 509.2 513.9 27171.3 32591.8 41290.0 42403.8 42690.4 43796.6 44195.0 44237.6 44414.8 44421.8 9766.4 9946.0 9949.0 9955.5 9959.0 8800.0 8953.2 11995.2 12165.9 12186.1 12178.9 12222.6 12249.3 12255.5 12283.6 8769.0 12104.8 12175.2 12191.0 12203.3 11661.5 11897.9 11908.2 12161.6 12203.8 41533.0 41879.6 41919.8 42131.2 42141.0 3257.8 3365.4 3554.7 3683.9 3814.3 30998.8 31116.0 31359.5 32300.7 33029.2 3219.6 3261.6 3277.5 3294.2 3420.7 530272.0 535199.0 538695.0 540082.0 541820.0 62441.0 62848.4 62917.4 62941.7 62975.7 35334.0 35804.9 35853.3 37439.8 37451.3 16449.3 16566.3 16625.0 16625.3 16673.0 11572.4 11722.3 12651.0 12768.3 12785.3 17397.0 17530.2 19251.1 20503.8 20534.3 7891.4 7924.6 7950.4 7952.4 7981.7 887.4 964.3 1034.2 1046.9 1048.3 30503.4 30593.0 30703.0 31221.8 31277.7 1076.1 1120.3 1159.4 1186.7 1239.4 15803.4 15885.9 15913.3 16080.4 16314.0 740466.0 743549.0 744368.0 749662.0 750585.0 59952.4 60618.0 60621.7 60637.0 60690.1 1268.2 1301.1 1305.7 1399.6 1418.8 31175.4 32877.0 33652.0 33802.0 33825.2 58107.4 59451.1 62335.7 62342.7 62371.0 659945.0 669840.0 671399.6 671571.0 672416.0 45745.5 46748.8 46796.8 47503.5 47604.8 50597.5 50672.0 50860.0 51772.0 52282.5 41126.8 41242.6 41927.8 42211.8 43348.6 35318.0 36159.3 40360.4 40858.1 40922.7 40438.6 40787.8 40913.0 41152.8 41256.8 30823.2 35609.1 36054.8 37024.8 37179.0 24820.0 24938.9 26683.0 27140.8 27343.3 03:50:27 INFO - PROCESS | 1944 | 03:50:27 INFO - PROCESS | 1944 | [#2] jquery.html Cycles:180 Average:37113.11 Median:26813.73 stddev:105823.46 (394.7%) stddev-sans-first:106084.32 03:50:27 INFO - PROCESS | 1944 | Values: 352.2 372.7 375.8 376.3 404.3 12402.3 16621.7 20359.5 20982.6 21076.8 21359.6 21369.5 21758.2 21862.5 21976.3 5290.6 5755.2 5858.5 6041.6 6130.2 5811.9 5953.0 5988.9 5994.7 6017.7 4150.3 4388.1 5872.9 6633.9 6716.5 6360.8 6690.3 6690.8 6705.4 6713.2 4781.5 5620.8 5655.0 5666.9 5670.8 26141.9 26711.7 26762.9 26864.6 26921.6 2280.8 2485.8 2672.0 2731.9 3092.4 16212.4 16218.0 16361.3 16698.3 16714.9 1878.6 1899.3 1909.7 1920.8 1943.9 120616.0 129642.0 130014.3 132580.3 133282.0 9272.2 10547.6 10716.9 10856.3 11071.2 13417.9 13467.8 13521.9 13549.4 13550.4 8415.5 8512.9 8520.4 8542.7 8636.6 8612.9 8616.3 8647.5 8789.3 8850.5 5615.8 6499.9 6564.2 6584.2 6589.6 1576.4 1814.8 1843.9 1858.3 1862.2 921.7 962.2 966.3 983.7 1021.7 16963.0 21131.0 21271.3 22244.6 22559.0 924.8 942.2 946.9 950.6 1017.1 11320.2 11708.5 11761.8 11944.3 11955.3 613469.0 627367.0 629507.0 638665.0 644370.0 142202.0 148441.0 150410.2 152851.0 153341.2 981.4 1011.2 1052.8 1058.0 1059.6 19015.5 19899.5 19952.7 19962.3 20029.2 54745.5 56076.0 56462.7 57297.1 57422.4 66277.0 80708.0 80901.2 82062.0 83038.3 7975.1 8082.5 8108.3 8143.7 8228.3 11544.0 12613.4 12619.3 12955.8 13235.0 5497.4 5754.7 10998.5 11405.1 11478.9 10864.0 10987.1 11009.6 11380.2 11425.8 11409.4 11577.4 11613.2 11632.9 11693.4 7857.3 7935.5 8087.3 8281.7 8309.3 5603.1 8505.8 9090.0 10880.9 11150.9 03:50:27 INFO - PROCESS | 1944 | 03:50:27 INFO - PROCESS | 1944 | [#3] mootools.html Cycles:180 Average:5677.19 Median:4879.31 stddev:3197.65 (65.5%) stddev-sans-first:3190.24 03:50:27 INFO - PROCESS | 1944 | Values: 1370.9 1525.0 1580.6 1795.4 1954.1 7074.7 7747.5 8275.1 8658.6 8873.4 8193.0 8742.9 8841.3 8856.2 8858.0 2220.8 2392.3 2392.4 2401.4 2410.5 1993.7 2301.4 2364.1 2396.7 2406.2 2350.8 2377.8 2380.6 2391.3 2397.4 2341.3 2365.4 2367.8 2381.2 2385.1 2328.2 2353.6 2355.0 2359.5 2360.5 7849.8 8901.6 8901.9 8912.1 8936.2 5219.5 5235.5 5413.4 5421.8 5428.7 3570.9 3656.0 3658.8 3659.8 3667.4 948.8 1070.1 1075.2 1086.8 1101.9 11544.8 11660.4 11678.9 11681.4 11688.0 3881.3 4424.9 4443.0 4593.8 4614.7 9157.0 9157.3 9171.9 9185.3 9214.7 5232.8 5241.1 5247.2 5252.7 5254.3 4414.0 4556.3 4810.1 4898.4 4925.8 3617.5 3646.5 3711.9 3805.1 3836.8 1136.9 1147.2 1161.2 1161.9 1167.9 841.9 914.4 939.3 973.2 985.7 5626.3 5847.9 5874.0 6074.3 6738.6 420.8 443.2 448.7 455.3 459.6 3954.3 3998.0 4055.8 4072.0 4118.2 9961.6 10610.2 10800.0 10807.5 10839.5 10284.4 10660.4 10700.5 10706.7 10744.6 7963.0 8351.3 8395.7 8403.0 8412.8 6009.6 6035.7 6135.6 6160.4 6182.0 9617.0 10035.4 10066.3 10068.8 10077.1 10187.8 10260.4 10270.1 10344.7 10362.5 6274.7 6957.3 7255.3 7479.9 7711.2 8383.6 8412.2 8514.6 8557.6 8581.8 7543.5 7648.3 7658.9 7706.2 7834.2 7366.9 7419.9 7430.1 7431.1 7508.1 7061.8 7087.8 7147.4 7161.7 7164.1 4860.2 4983.8 5015.5 5066.7 5223.9 4990.1 5009.1 5037.7 5216.5 5334.4 03:50:27 INFO - PROCESS | 1944 | 03:50:27 INFO - PROCESS | 1944 | [#4] prototype.html Cycles:180 Average:4444.59 Median:5032.81 stddev:1360.19 (27.0%) stddev-sans-first:1327.89 03:50:27 INFO - PROCESS | 1944 | Values: 296.6 297.4 310.2 314.3 317.0 5200.6 5257.9 5264.4 5290.6 5299.0 4550.7 4659.0 4941.7 5230.2 5291.4 4030.7 4062.8 4069.7 4083.0 4118.9 4020.9 4093.8 4094.5 4095.1 4096.7 3356.0 3544.1 3695.6 3789.6 4026.9 3901.4 4025.7 4033.9 4056.7 4070.5 3512.1 3514.8 3516.5 3537.3 3541.2 4772.6 5109.9 5113.0 5149.5 5217.9 3804.9 4406.3 4548.4 4556.6 4572.3 5219.8 5467.4 5499.3 5518.3 5526.6 3361.4 3369.1 3484.9 3772.5 3822.5 4929.6 5351.7 5793.5 5811.8 5843.9 3221.8 3800.8 3826.5 3832.1 3834.1 3874.7 3900.1 3910.3 3915.7 3931.9 4112.9 4169.6 4524.8 4534.2 4556.6 5289.1 5482.3 5512.5 5518.6 5544.3 1091.7 1203.4 1223.5 1332.2 1337.3 806.5 823.3 843.5 866.4 881.0 5006.8 5009.4 5014.9 5038.1 5088.4 4482.0 5483.0 5499.3 5512.3 5528.2 4221.5 4694.9 4980.5 5021.3 5027.5 2367.8 2941.4 2967.3 2978.7 2981.0 5046.1 5761.3 5768.0 5768.6 5873.9 5452.4 5588.0 5748.3 5766.7 5774.2 5289.3 5305.5 5785.8 5811.4 5832.6 4876.7 5534.2 5540.1 5631.6 5694.0 4384.6 5190.4 5467.6 5470.7 5499.7 5117.9 5121.0 5447.3 5666.0 5711.5 3519.9 3657.6 3749.3 3773.3 3791.7 5547.9 5632.9 5636.6 5654.5 5667.2 4817.9 5465.7 5478.7 5529.9 5538.5 4646.8 5081.5 5422.7 5502.5 5610.6 4482.7 5159.0 5325.8 5334.4 5380.7 5341.1 5436.9 5511.0 5528.0 5530.3 4498.0 4733.9 5339.5 5378.2 5415.6 03:50:27 INFO - PROCESS | 1944 | 03:50:27 INFO - PROCESS | 1944 | [#5] yui.html Cycles:180 Average:25438.30 Median:3572.24 stddev:40370.78 (1130.1%) stddev-sans-first:40441.31 03:50:27 INFO - PROCESS | 1944 | Values: 703.3 750.8 792.0 843.5 844.9 15599.5 16288.0 16306.8 16308.0 16316.0 14614.2 14765.6 14773.6 14787.3 14883.6 712.5 738.0 893.6 898.9 927.8 900.4 908.5 915.6 916.7 919.0 971.7 973.7 974.8 975.2 976.0 744.9 926.1 968.9 970.0 970.2 718.2 720.6 722.9 723.2 723.6 15244.9 15290.9 15299.4 15322.8 15352.7 13986.2 14148.4 14272.2 14412.6 14514.2 13190.7 13333.7 13507.9 13538.0 13856.6 4584.4 4651.8 4703.6 4719.5 4887.0 123768.1 125874.4 126125.4 127108.7 128459.0 11173.4 11292.7 11316.4 11324.5 11725.9 23221.8 23235.7 23238.0 23245.4 23268.9 7968.2 8062.6 8075.0 8161.6 8315.2 4401.4 4472.2 4557.0 4643.6 4675.1 3450.4 3551.1 3570.9 3573.6 3587.9 635.4 639.9 641.0 661.6 666.7 617.8 622.7 627.0 644.6 652.5 7943.3 9073.1 9274.3 9307.5 9475.3 622.7 640.4 643.2 646.9 649.9 3803.5 4528.8 4552.5 4557.1 4622.9 96188.0 126415.4 131994.3 164251.1 164389.2 138001.3 140968.6 141076.0 141157.0 145200.0 104048.0 105412.0 106695.0 108430.5 109894.4 8536.7 8726.7 8782.9 8810.2 8953.6 65180.0 66072.7 66131.0 69013.7 70014.0 70738.6 78882.0 81466.0 89318.7 97000.0 10886.6 13694.3 13721.5 13752.0 14439.9 15439.5 15866.4 15884.7 16392.3 16419.6 14295.6 14416.8 14471.9 14603.7 14866.9 13982.9 14235.3 14573.3 14614.8 14791.1 13886.7 14106.3 14110.0 14501.7 14581.8 11557.9 11571.6 11731.9 11860.7 11988.8 8872.2 10838.2 11444.0 11519.8 11846.2 03:50:27 INFO - PROCESS | 1944 | -------- Summary: end -------- 03:50:27 INFO - PROCESS | 1944 | 03:50:28 INFO - TEST-INFO | 1944: exit 0 03:50:28 INFO - TEST-OK | dromaeo_css | took 1117841ms 03:50:28 INFO - TEST-START | kraken 03:50:28 INFO - operating with platform_type : mac_ 03:50:28 INFO - Initialising browser for kraken test... 03:50:28 INFO - TEST-INFO | started process 1968 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmpd6pXJA/profile http://localhost:56045/getInfo.html) 03:50:30 INFO - PROCESS | 1968 | JavaScript error: , line 0: uncaught exception: undefined 03:50:30 INFO - PROCESS | 1968 | __metrics Screen width/height:1600/1200 03:50:30 INFO - PROCESS | 1968 | colorDepth:24 03:50:30 INFO - PROCESS | 1968 | Browser inner width/height: 1024/689 03:50:30 INFO - PROCESS | 1968 | __metrics 03:50:30 INFO - PROCESS | 1968 | 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" 03:50:30 INFO - PROCESS | 1968 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:50:31 INFO - TEST-INFO | 1968: exit 0 03:50:31 INFO - Browser initialized. 03:50:31 INFO - Running cycle 1/1 for kraken test... 03:50:31 INFO - TEST-INFO | started process 1969 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmpd6pXJA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:50:43 INFO - PROCESS | 1969 | RSS: Main: 245571584 03:50:43 INFO - PROCESS | 1969 | 03:50:46 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56045/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56045/tests/kraken/driver.html?testName=audio-beat-detection) 03:50:47 INFO - PROCESS | 1969 | RSS: Main: 325124096 03:50:47 INFO - PROCESS | 1969 | 03:50:49 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56045/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56045/tests/kraken/driver.html?testName=audio-dft) 03:50:50 INFO - PROCESS | 1969 | RSS: Main: 335826944 03:50:50 INFO - PROCESS | 1969 | 03:50:53 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56045/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56045/tests/kraken/driver.html?testName=audio-fft) 03:50:54 INFO - PROCESS | 1969 | RSS: Main: 428314624 03:50:54 INFO - PROCESS | 1969 | 03:50:56 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56045/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56045/tests/kraken/driver.html?testName=audio-oscillator) 03:50:57 INFO - PROCESS | 1969 | RSS: Main: 330973184 03:50:57 INFO - PROCESS | 1969 | 03:50:59 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56045/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56045/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:51:00 INFO - PROCESS | 1969 | RSS: Main: 334917632 03:51:00 INFO - PROCESS | 1969 | 03:51:02 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56045/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56045/tests/kraken/driver.html?testName=imaging-darkroom) 03:51:03 INFO - PROCESS | 1969 | RSS: Main: 372019200 03:51:03 INFO - PROCESS | 1969 | 03:51:06 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56045/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56045/tests/kraken/driver.html?testName=imaging-desaturate) 03:51:07 INFO - PROCESS | 1969 | RSS: Main: 374251520 03:51:07 INFO - PROCESS | 1969 | 03:51:09 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56045/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56045/tests/kraken/driver.html?testName=json-parse-financial) 03:51:10 INFO - PROCESS | 1969 | RSS: Main: 375336960 03:51:10 INFO - PROCESS | 1969 | 03:51:12 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56045/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56045/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:51:12 INFO - PROCESS | 1969 | RSS: Main: 350732288 03:51:12 INFO - PROCESS | 1969 | 03:51:14 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56045/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56045/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:51:15 INFO - PROCESS | 1969 | RSS: Main: 356835328 03:51:15 INFO - PROCESS | 1969 | 03:51:17 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56045/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56045/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:51:18 INFO - PROCESS | 1969 | RSS: Main: 354918400 03:51:18 INFO - PROCESS | 1969 | 03:51:20 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56045/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56045/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:51:21 INFO - PROCESS | 1969 | RSS: Main: 363528192 03:51:21 INFO - PROCESS | 1969 | 03:51:24 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56045/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56045/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:51:25 INFO - PROCESS | 1969 | RSS: Main: 366215168 03:51:25 INFO - PROCESS | 1969 | 03:51:26 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56045/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56045/tests/kraken/driver.html?testName=ai-astar) 03:51:27 INFO - PROCESS | 1969 | RSS: Main: 409415680 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - PROCESS | 1969 | __start_tp_report 03:51:27 INFO - PROCESS | 1969 | _x_x_mozilla_page_load 03:51:27 INFO - PROCESS | 1969 | _x_x_mozilla_page_load_details 03:51:27 INFO - PROCESS | 1969 | |i|pagename|runs| 03:51:27 INFO - PROCESS | 1969 | |0;ai-astar;102;118;162;166;115;112;112;113;113;117 03:51:27 INFO - PROCESS | 1969 | |1;audio-beat-detection;145;105;105;170;104;106;134;104;139;106 03:51:27 INFO - PROCESS | 1969 | |2;audio-dft;184;189;185;184;186;184;181;185;185;183 03:51:27 INFO - PROCESS | 1969 | |3;audio-fft;76;58;57;105;59;79;87;94;84;57 03:51:27 INFO - PROCESS | 1969 | |4;audio-oscillator;89;67;93;74;72;70;100;69;69;103 03:51:27 INFO - PROCESS | 1969 | |5;imaging-gaussian-blur;90;87;90;87;88;86;90;86;91;88 03:51:27 INFO - PROCESS | 1969 | |6;imaging-darkroom;94;94;106;150;111;93;93;95;95;95 03:51:27 INFO - PROCESS | 1969 | |7;imaging-desaturate;86;84;87;85;85;86;91;84;86;130 03:51:27 INFO - PROCESS | 1969 | |8;json-parse-financial;56;54;55;55;55;52;53;52;52;51 03:51:27 INFO - PROCESS | 1969 | |9;json-stringify-tinderbox;67;80;78;70;69;72;73;72;73;75 03:51:27 INFO - PROCESS | 1969 | |10;stanford-crypto-aes;82;83;83;83;83;82;82;86;85;81 03:51:27 INFO - PROCESS | 1969 | |11;stanford-crypto-ccm;137;139;139;136;131;137;141;138;210;133 03:51:27 INFO - PROCESS | 1969 | |12;stanford-crypto-pbkdf2;162;162;162;164;163;164;163;165;167;162 03:51:27 INFO - PROCESS | 1969 | |13;stanford-crypto-sha256-iterative;68;78;86;85;69;67;67;66;66;66 03:51:27 INFO - PROCESS | 1969 | __end_tp_report 03:51:27 INFO - PROCESS | 1969 | __start_cc_report 03:51:27 INFO - PROCESS | 1969 | _x_x_mozilla_cycle_collect,2144 03:51:27 INFO - PROCESS | 1969 | __end_cc_report 03:51:27 INFO - PROCESS | 1969 | __startTimestamp1469616687124__endTimestamp 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - PROCESS | 1969 | ------- Summary: start ------- 03:51:27 INFO - PROCESS | 1969 | Number of tests: 14 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - PROCESS | 1969 | [#0] ai-astar Cycles:10 Average:123.00 Median:116.00 stddev:22.06 (19.0%) stddev-sans-first:22.05 03:51:27 INFO - PROCESS | 1969 | Values: 102.0 118.0 162.0 166.0 115.0 112.0 112.0 113.0 113.0 117.0 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - PROCESS | 1969 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:120.00 stddev:23.58 (19.6%) stddev-sans-first:23.47 03:51:27 INFO - PROCESS | 1969 | Values: 145.0 105.0 105.0 170.0 104.0 106.0 134.0 104.0 139.0 106.0 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - PROCESS | 1969 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.00 stddev:2.07 (1.1%) stddev-sans-first:2.18 03:51:27 INFO - PROCESS | 1969 | Values: 184.0 189.0 185.0 184.0 186.0 184.0 181.0 185.0 185.0 183.0 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - PROCESS | 1969 | [#3] audio-fft Cycles:10 Average:75.60 Median:77.50 stddev:17.30 (22.3%) stddev-sans-first:18.34 03:51:27 INFO - PROCESS | 1969 | Values: 76.0 58.0 57.0 105.0 59.0 79.0 87.0 94.0 84.0 57.0 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - PROCESS | 1969 | [#4] audio-oscillator Cycles:10 Average:80.60 Median:71.00 stddev:14.09 (19.8%) stddev-sans-first:14.61 03:51:27 INFO - PROCESS | 1969 | Values: 89.0 67.0 93.0 74.0 72.0 70.0 100.0 69.0 69.0 103.0 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - PROCESS | 1969 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:89.00 stddev:1.83 (2.1%) stddev-sans-first:1.83 03:51:27 INFO - PROCESS | 1969 | Values: 90.0 87.0 90.0 87.0 88.0 86.0 90.0 86.0 91.0 88.0 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - PROCESS | 1969 | [#6] imaging-darkroom Cycles:10 Average:102.60 Median:94.00 stddev:17.75 (18.9%) stddev-sans-first:18.55 03:51:27 INFO - PROCESS | 1969 | Values: 94.0 94.0 106.0 150.0 111.0 93.0 93.0 95.0 95.0 95.0 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - PROCESS | 1969 | [#7] imaging-desaturate Cycles:10 Average:90.40 Median:86.00 stddev:14.06 (16.3%) stddev-sans-first:14.82 03:51:27 INFO - PROCESS | 1969 | Values: 86.0 84.0 87.0 85.0 85.0 86.0 91.0 84.0 86.0 130.0 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - PROCESS | 1969 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.50 stddev:1.72 (3.1%) stddev-sans-first:1.56 03:51:27 INFO - PROCESS | 1969 | Values: 56.0 54.0 55.0 55.0 55.0 52.0 53.0 52.0 52.0 51.0 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - PROCESS | 1969 | [#9] json-stringify-tinderbox Cycles:10 Average:72.90 Median:73.00 stddev:3.96 (5.4%) stddev-sans-first:3.57 03:51:27 INFO - PROCESS | 1969 | Values: 67.0 80.0 78.0 70.0 69.0 72.0 73.0 72.0 73.0 75.0 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - PROCESS | 1969 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.00 stddev:1.49 (1.8%) stddev-sans-first:1.54 03:51:27 INFO - PROCESS | 1969 | Values: 82.0 83.0 83.0 83.0 83.0 82.0 82.0 86.0 85.0 81.0 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - PROCESS | 1969 | [#11] stanford-crypto-ccm Cycles:10 Average:144.10 Median:138.50 stddev:23.34 (16.9%) stddev-sans-first:24.61 03:51:27 INFO - PROCESS | 1969 | Values: 137.0 139.0 139.0 136.0 131.0 137.0 141.0 138.0 210.0 133.0 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - PROCESS | 1969 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.40 Median:163.50 stddev:1.65 (1.0%) stddev-sans-first:1.67 03:51:27 INFO - PROCESS | 1969 | Values: 162.0 162.0 162.0 164.0 163.0 164.0 163.0 165.0 167.0 162.0 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - PROCESS | 1969 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:68.50 stddev:8.05 (11.8%) stddev-sans-first:8.42 03:51:27 INFO - PROCESS | 1969 | Values: 68.0 78.0 86.0 85.0 69.0 67.0 67.0 66.0 66.0 66.0 03:51:27 INFO - PROCESS | 1969 | -------- Summary: end -------- 03:51:27 INFO - PROCESS | 1969 | 03:51:27 INFO - TEST-INFO | 1969: exit 0 03:51:27 INFO - TEST-OK | kraken | took 59677ms 03:51:27 INFO - SUITE-END | took 1177s 03:51:28 INFO - Completed test suite (00:19:37) 03:51:28 INFO - javascript benchmark 03:51:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3338.5993680758297, 3357.8153201573914, 3373.122988023114, 3384.999999999997, 3413.0102270341604, 5590.468905453975, 5681.744321505532, 5700.37295897451, 5761.366249712528, 5775.855603609902, 5745.540356771458, 5754.827355179341, 5764.164196191539, 5900.2919649642, 5924.111383292508, 5113.616478764089, 5192.299039629658, 5284.339457567804, 5303.628745987782, 5331.600129990266, 5289.259503669489, 5338.092524270864, 5339.759710813023, 5341.092014357569, 5362.088444964346, 4332.761698106603, 5099.107656160176, 5126.5898728101665, 5141.820036298749, 5204.505571970694, 5157.5616072633875, 5188.5849132069725, 5189.299444574972, 5194.896102077975, 5196.558292545138, 5144.3312369392115, 5157.974210128963, 5197.5322220999915, 5227.163653815372, 5287.920681189786, 5682.147677848315, 5710.857228569277, 5738.167965645, 5779.075347944308, 5798.362180160179, 6235.594686345418, 6240.00159974402, 6295.2760432550385, 6334.556581039329, 6340.3659634036685, 6121.056693972082, 6218.906716399258, 6256.155419018465, 6345.0, 6374.362563743635, 5815.534757219412, 5822.82531524055, 5826.854328641775, 5827.184194212771, 5865.912011319833, 6273.592216505913, 6276.0, 6290.685465726695, 6291.307956124822, 6303.684815759194, 6038.215032045894, 6080.269881430733, 6125.9892117800955, 6128.540359472968, 6163.96918015416, 6295.622262664255, 6317.052442133717, 6327.588706734047, 6327.683615819283, 6340.492760579143, 6256.593321434092, 6261.467775239155, 6271.560990730605, 6272.655003974825, 6277.7175027123885, 6100.1270446244, 6190.50475961922, 6224.968875155686, 6236.126942228092, 6306.211723534559, 4906.975465122723, 4924.089043526959, 4939.8271060513425, 4970.130227210296, 5002.374703162105, 4432.468103827497, 4444.555519456189, 4446.688731788744, 4451.043025749479, 4505.752183629932, 5733.312002559636, 5762.164486149485, 5765.250517432707, 5766.221560089418, 5864.8533786654825, 6130.826205142237, 6205.03821907605, 6244.250689917239, 6257.061440783918, 6326.019466982625, 5748.367679555246, 5769.85575360611, 5783.306003279549, 5810.924978878921, 5824.068149096209, 4646.605038571692, 5196.272521846944, 5434.755436005389, 5448.291722076184, 5464.644798088111, 6150.507959363151, 6284.748610055546, 6307.96846015767, 6333.398327158912, 6340.048992651139, 6224.906626400517, 6273.3099359071375, 6289.622622642473, 6318.115463835159, 6351.364863513611, 6297.2128483939505, 6316.210473690789, 6328.0, 6329.936700632935, 6344.71448784815, 6062.181605483203, 6218.968905155445, 6224.346443623354, 6236.937630623635, 6239.656818874824, 6253.468455181361, 6266.780662676967, 6271.686415679289, 6335.619862808247, 6349.523785715997, 6140.109684095871, 6154.538409619386, 6155.569110162246, 6164.630122192682, 6181.350958149509, 5921.407859214044, 6142.662153581417, 6153.6923153841235, 6161.137440758387, 6171.043488259327, 4918.975405122951, 5913.763449461973, 5926.140709597171, 5958.880822383441, 6021.638701677738, 6111.174991376285, 6111.537809342371, 6153.077038444269, 6158.938410615837, 6191.19514463117, 5776.162456443708, 5866.67733274674, 5980.282366116094, 5991.310999234969, 6023.096535519707], "value": 5717.110636523137, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.6972598085263, 465.2625588442319, 492.21983156696655, 509.24377299709926, 513.8869448721281, 27171.32071698206, 32591.837040814793, 41290.0, 42403.78798106005, 42690.359644605356, 43796.56203437973, 44195.0, 44237.557624423745, 44414.77792611033, 44421.77789111058, 9766.365186262905, 9945.950270248639, 9949.005099490028, 9955.452450115241, 9959.0, 8800.0, 8953.194212520872, 11995.22031067982, 12165.878341216607, 12186.14696971208, 12178.878211217907, 12222.594401643835, 12249.265044097383, 12255.509779608805, 12283.570075047375, 8769.0, 12104.81842772359, 12175.208611440228, 12191.0, 12203.328816915111, 11661.475233614508, 11897.917864373787, 11908.16642835002, 12161.574344897974, 12203.816942745865, 41533.0, 41879.58120418818, 41919.7904010478, 42131.1573768526, 42141.0, 3257.843465569736, 3365.444701624229, 3554.662307080823, 3683.907902302437, 3814.275287695357, 30998.845005775052, 31116.0, 31359.529607055912, 32300.67699323, 33029.17427064318, 3219.645838957713, 3261.59230096238, 3277.5411442397844, 3294.2093897464674, 3420.675035871588, 530272.0, 535199.0, 538695.0, 540081.9959002035, 541819.999999996, 62441.0, 62848.371516285166, 62917.37082629207, 62941.68529157416, 62975.6851215741, 35334.0, 35804.92585222448, 35853.282934341165, 37439.812800936365, 37451.250974980896, 16449.25978330978, 16566.337346506247, 16625.0, 16625.251863666163, 16672.999620022838, 11572.36352000648, 11722.296662200295, 12651.0, 12768.297743624189, 12785.296808675426, 17396.95618262908, 17530.211140498708, 19251.147657010137, 20503.794962050488, 20534.281300154424, 7891.368690504746, 7924.564148971862, 7950.363970882316, 7952.36381089523, 7981.725208315718, 887.4143065576689, 964.3249725192394, 1034.2036631793476, 1046.8691413573304, 1048.2871647279771, 30503.389932201233, 30593.0, 30703.0, 31221.8438907804, 31277.687223127934, 1076.101455284846, 1120.3166068698088, 1159.4376727287427, 1186.691460220349, 1239.4360565942475, 15803.36786528526, 15885.887987840972, 15913.283902224197, 16080.43718469848, 16314.0, 740466.0, 743549.0, 744368.0, 749662.0, 750585.0, 59952.40047599556, 60618.0, 60621.69689151526, 60637.0, 60690.0896486553, 1268.2010333490027, 1301.1087405127707, 1305.738852229539, 1399.55214331415, 1418.7729963205838, 31175.37649246957, 32877.01368958935, 33652.0, 33802.0, 33825.15437114092, 58107.4189258102, 59451.108233377396, 62335.688321558104, 62342.688286558274, 62371.0, 659945.0, 669840.0, 671399.6430018015, 671571.0, 672416.0, 45745.54254457413, 46748.7662561685, 46796.7660161697, 47503.52496474991, 47604.7619761899, 50597.49402505928, 50672.0, 50860.0, 51771.964560709326, 52282.47717522928, 41126.79436602798, 41242.58757412388, 41927.790361048, 42211.78894105633, 43348.56651433445, 35318.0, 36159.27681446409, 40360.3945940817, 40858.1399302046, 40922.703116173565, 40438.59561404466, 40787.79606101951, 40913.0, 41152.794236028625, 41256.79371603243, 30823.2294192647, 35609.1097722559, 36054.8197259012, 37024.81487592545, 37179.0, 24820.00719971181, 24938.875305623355, 26683.0, 27140.810550434097, 27343.316417089733], "value": 21226.263392244568, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.1618547856102, 372.70928675633, 375.8027035806203, 376.26440309195516, 404.2783631218274, 12402.255864648127, 16621.66756664866, 20359.491012724688, 20982.58034839302, 21076.789232107694, 21359.57280854382, 21369.465763355925, 21758.200037965453, 21862.45343866404, 21976.34070977873, 5290.576753859701, 5755.194272801811, 5858.502027327671, 6041.6375017499095, 6130.172426722403, 5811.941880581182, 5952.970235148819, 5988.8502787430225, 5994.700264986755, 6017.699115044252, 4150.3359462486005, 4388.056567837914, 5872.882542349151, 6633.867322653568, 6716.529842910998, 6360.840978975516, 6690.330966903295, 6690.83272918176, 6705.396514313687, 6713.227978782429, 4781.497942716, 5620.775168993236, 5655.0, 5666.943330566724, 5670.8015219467125, 26141.869290653423, 26711.73288267112, 26762.866185669143, 26864.59703104455, 26921.596176057374, 2280.7605201453775, 2485.77567341742, 2671.9846458345896, 2731.934545527246, 3092.4279008383296, 16212.351505939749, 16218.0, 16361.263743131587, 16698.332066717318, 16714.916425417796, 1878.6473738907964, 1899.259288877339, 1909.713542968552, 1920.836728878047, 1943.9416817495498, 120616.0, 129642.0, 130014.3499282507, 132580.33709831486, 133282.0, 9272.211861991671, 10547.630832920964, 10716.892831071746, 10856.34861908288, 11071.225014248925, 13417.932910335387, 13467.797983030263, 13521.932390338183, 13549.390277437538, 13550.390232439564, 8415.495070295801, 8512.914870851213, 8520.446170998865, 8542.701005464904, 8636.611352489153, 8612.8708069379, 8616.310695144373, 8647.535452396163, 8789.34079943994, 8850.513221772864, 5615.820677657738, 6499.8625240582705, 6564.245111812107, 6584.209894812653, 6589.604623722592, 1576.393088660851, 1814.7550080738931, 1843.8617103717274, 1858.2659849359698, 1862.1527205121638, 921.6820197032014, 962.1629182611173, 966.3042609321277, 983.6999715086968, 1021.6526381030485, 16963.0, 21131.04910279041, 21271.255506057212, 22244.55510889773, 22559.0, 924.8242833861544, 942.236788201559, 946.9005754395689, 950.5912457643279, 1017.0998666180484, 11320.150988675718, 11708.515962765869, 11761.823572646417, 11944.343061131547, 11955.282683039046, 613469.0, 627367.0, 629507.0, 638665.0, 644370.0, 142202.0, 148441.0, 150410.24794875956, 152851.0, 153341.2332938328, 981.3817295104038, 1011.2365164300905, 1052.7841792432532, 1058.0266155137292, 1059.5602824827765, 19015.52461188481, 19899.502512437306, 19952.700709489658, 19962.30131945375, 20029.198832046553, 54745.452545474036, 56076.0, 56462.717686411306, 57297.140542892725, 57422.42577574171, 66277.0, 80708.0, 80901.19098808937, 82062.0, 83038.3392332162, 7975.1227364988645, 8082.4746391486715, 8108.270255677017, 8143.714969976022, 8228.300594449538, 11544.0, 12613.369331533204, 12619.30593817349, 12955.805662915252, 13235.0, 5497.367802702581, 5754.7122643866805, 10998.450077496253, 11405.144614153805, 11478.88521114778, 10863.994369241038, 10987.066099381642, 11009.61466348674, 11380.203385762918, 11425.828612570653, 11409.429528523706, 11577.421128943688, 11613.187076904536, 11632.941835290769, 11693.356865372487, 7857.319791924697, 7935.523868567904, 8087.312578430664, 8281.710140145307, 8309.293710034473, 5603.103503439593, 8505.766663833832, 9090.0, 10880.919480597298, 11150.888491114985], "value": 9863.06445596063, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1370.9245991470477, 1525.001124263607, 1580.5574439157037, 1795.416489640867, 1954.1304119666745, 7074.68163932623, 7747.535147891131, 8275.08974012859, 8658.610362533685, 8873.378863479558, 8192.959035204816, 8742.86885696715, 8841.336899732514, 8856.247218986378, 8857.95571022144, 2220.844540882139, 2392.342105920877, 2392.35406440261, 2401.4236583220077, 2410.517896420714, 1993.6610776168086, 2301.4476525633813, 2364.13708996216, 2396.676448679423, 2406.2300063979533, 2350.8471949323325, 2377.8335516513853, 2380.5833979053596, 2391.3184742348435, 2397.3886658901956, 2341.2625023117835, 2365.361352434833, 2367.75138610446, 2381.2499062795164, 2385.1294277588727, 2328.2087524324706, 2353.623420252751, 2355.0, 2359.4691194481106, 2360.5042940982416, 7849.843003139905, 8901.554922253927, 8901.910980890172, 8912.108789121101, 8936.151065648754, 5219.530242278213, 5235.450277720823, 5413.431589683066, 5421.783128674848, 5428.67427954324, 3570.9107272318142, 3656.0, 3658.798766067892, 3659.8170091495317, 3667.3765459871884, 948.8481842905171, 1070.1213735052684, 1075.1936047964027, 1086.7772106718103, 1101.9228653994223, 11544.769104617944, 11660.358680272582, 11678.941605292004, 11681.415929203506, 11688.006519445942, 3881.2819628368843, 4424.867253982386, 4442.977785111054, 4593.770311484413, 4614.723116613014, 9156.954215228881, 9157.313201509913, 9171.90828091724, 9185.265178785681, 9214.723558293263, 5232.843014709565, 5241.082810508222, 5247.2129180623215, 5252.684838909678, 5254.290670759475, 4413.955860441419, 4556.339330797082, 4810.13417584838, 4898.4121905371585, 4925.827596034122, 3617.5297211362354, 3646.5385864541327, 3711.870084547082, 3805.124821291063, 3836.7506112102624, 1136.8578927634046, 1147.2198904744687, 1161.1697636189995, 1161.9477123529462, 1167.8656954450178, 841.852675781748, 914.3827916156567, 939.3330735177964, 973.2165606686543, 985.6621921047732, 5626.324841019022, 5847.883042339129, 5874.0, 6074.331823499411, 6738.629375384401, 420.7938110325918, 443.24205608409983, 448.7487007275936, 455.3397573518412, 459.5519368615573, 3954.268460334847, 3997.980010099931, 4055.797210139481, 4071.9796401017807, 4118.19695159441, 9961.601535938482, 10610.20423468243, 10800.0, 10807.513661885234, 10839.512221950032, 10284.434356110336, 10660.360378377321, 10700.518476668569, 10706.73233169177, 10744.623938162284, 7963.0046244219475, 8351.331893448509, 8395.664173433117, 8403.0, 8412.8317433651, 6009.579329447071, 6035.668038257938, 6135.631862088289, 6160.44555989963, 6182.0, 9617.0, 10035.448050357008, 10066.345687530287, 10068.84896726535, 10077.14344280715, 10187.84718229242, 10260.435676037889, 10270.127039201452, 10344.741381465523, 10362.481875906024, 6274.745050989729, 6957.304269573205, 7255.310745479171, 7479.88780168287, 7711.228877112469, 8383.622736976973, 8412.200840920103, 8514.61684224199, 8557.61490732906, 8581.828363432822, 7543.47195696296, 7648.34989025939, 7658.923410765821, 7706.229377062249, 7834.216578342121, 7366.926330736838, 7419.9258007421395, 7430.108386993596, 7431.108267007994, 7508.0990281166605, 7061.788146355618, 7087.751928682472, 7147.428205743632, 7161.713531458683, 7164.104486939133, 4860.198067318853, 4983.75081245929, 5015.473375295542, 5066.746662666926, 5223.947760522498, 4990.126727822689, 5009.148444764326, 5037.672551284155, 5216.452272511484, 5334.439883812144], "value": 4480.9401913246975, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.62180719582517, 297.3621581707238, 310.24919694339695, 314.3006809848087, 316.9762267829913, 5200.595839123434, 5257.92113118303, 5264.420673797865, 5290.624437646211, 5298.96670149321, 4550.658700597452, 4658.97670511647, 4941.703497790135, 5230.189320655304, 5291.3650361956525, 4030.6976976726714, 4062.796860156995, 4069.69477289203, 4082.9795851020704, 4118.917621647565, 4020.8793736187813, 4093.7953102344914, 4094.529129150141, 4095.1195492969005, 4096.713230073896, 3355.9664403355896, 3544.0962554548555, 3695.6489133532405, 3789.602091780365, 4026.899327516821, 3901.4342920276545, 4025.657819085381, 4033.858814941463, 4056.655184309337, 4070.5318888327924, 3512.102515424759, 3514.824258787076, 3516.5252690886914, 3537.2571759930447, 3541.1678255609913, 4772.618190544783, 5109.875827318021, 5113.0, 5149.48505149482, 5217.947820521784, 3804.9429258561145, 4406.325596273427, 4548.385967894325, 4556.589906908394, 4572.314152877062, 5219.817306394296, 5467.371252305996, 5499.285092937933, 5518.33779946405, 5526.613137080406, 3361.428557145291, 3369.1240277527772, 3484.930301393958, 3772.4529943158363, 3822.4839646647624, 4929.556339929388, 5351.732413379315, 5793.478586927196, 5811.767529298823, 5843.941560584382, 3221.8066915985114, 3800.847966081326, 3826.5408151022057, 3832.1222600925344, 3834.1373191031794, 3874.7287689862, 3900.1224724437343, 3910.335243008695, 3915.7288772511874, 3931.8820435386983, 4112.876613701594, 4169.60388763067, 4524.841630542981, 4534.229181039232, 4556.567126123013, 5289.127293996447, 5482.2873026506295, 5512.5314348280845, 5518.586106042063, 5544.306961629796, 1091.67795500326, 1203.4223572684984, 1223.4861358229564, 1332.160738734591, 1337.3246510512129, 806.5443024691148, 823.2755175445569, 843.5023336231653, 866.4317585170861, 880.9868651051343, 5006.799728010911, 5009.44896061433, 5014.918808694351, 5038.1435156022835, 5088.354055890916, 4482.0, 5483.0, 5499.285092937893, 5512.283403157564, 5528.226048353234, 4221.472315960505, 4694.88262793426, 4980.452150263468, 5021.271915572222, 5027.4721154279005, 2367.8105751539833, 2941.4410362507906, 2967.2730181105335, 2978.687237840039, 2980.970190298069, 5046.066477701564, 5761.337446193741, 5767.971160144172, 5768.596198266081, 5873.853153671193, 5452.36429071285, 5588.0, 5748.281464816898, 5766.711664416763, 5774.249347584787, 5289.338832645919, 5305.522502974751, 5785.768569257267, 5811.360750317462, 5832.591718579743, 4876.707397556158, 5534.225208470737, 5540.1135818270495, 5631.605787594829, 5693.971530142323, 4384.649228061684, 5190.429052804264, 5467.644603100785, 5470.72646367688, 5499.7250137492165, 5117.874067705099, 5120.974395128001, 5447.319085114361, 5666.0, 5711.543556393093, 3519.894403167909, 3657.579378371521, 3749.3063783200196, 3773.3019391412677, 3791.658750712449, 5547.861303467284, 5632.88734225305, 5636.633618814928, 5654.519365853948, 5667.202974007342, 4817.927731084106, 5465.699386533779, 5478.671279723229, 5529.944700552943, 5538.510738234886, 4646.8141274348645, 5081.517255860687, 5422.66825265124, 5502.477264659851, 5610.615488417325, 4482.731036137842, 5158.97420512895, 5325.840224793263, 5334.439883812144, 5380.677159370451, 5341.118715411913, 5436.945630543644, 5511.035568775528, 5528.0, 5530.253415788817, 4498.0, 4733.9053218936115, 5339.4660533947845, 5378.247045413724, 5415.620906536505], "value": 4006.838280295758, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.2721133626696, 750.849830033993, 792.04162962815, 843.493903657805, 844.8690452979788, 15599.454019109333, 16288.0, 16306.755398669015, 16308.021518708885, 16316.021038737685, 14614.196219207966, 14765.557033288991, 14773.630659233524, 14787.260636968162, 14883.627909302273, 712.5119293284091, 738.0073800737999, 893.6246776353947, 898.8921329440476, 927.7634203278154, 900.4327273817519, 908.4957848394139, 915.6337465013982, 916.6974898283551, 918.9862152067691, 971.6939164163266, 973.6543527047902, 974.8099120671471, 975.2246963663904, 975.9658411955583, 744.8510297940379, 926.1109335038371, 968.9321747477644, 970.0, 970.1995853420921, 718.2422544215902, 720.5784615999646, 722.869883420984, 723.2405973727565, 723.5622448418684, 15244.923775381052, 15290.923545382202, 15299.388024479007, 15322.770158447633, 15352.692946141015, 13986.16083035011, 14148.363323650461, 14272.215028173447, 14412.63968400788, 14514.201718905457, 13190.736185276337, 13333.733325333536, 13507.932460337734, 13538.0, 13856.584302470841, 4584.4498660161, 4651.837185698518, 4703.647226457995, 4719.5044520325555, 4886.97373551551, 123768.1434778479, 125874.37062814718, 126125.36937315346, 127108.72891271062, 128459.0, 11173.385463799406, 11292.661220163409, 11316.377599231957, 11324.547018119347, 11725.941370293094, 23221.7677823223, 23235.651465228035, 23237.954292056897, 23245.41886452852, 23268.883655581612, 7968.163342848918, 8062.55655938929, 8075.011477274911, 8161.63272652732, 8315.168483151758, 4401.361802538678, 4472.217361961709, 4557.043020965568, 4643.628509719214, 4675.13510000651, 3450.413429716954, 3551.076720052805, 3570.8750193743613, 3573.6069032406413, 3587.9282414351565, 635.4090695653088, 639.9424051835357, 640.9775657851952, 661.6460193796296, 666.7133132752963, 617.8270084376381, 622.707327556048, 626.9937300627026, 644.5810223354857, 652.5334385913999, 7943.324817390471, 9073.12167362847, 9274.287498946544, 9307.454345873746, 9475.28935329853, 622.651315263454, 640.3596403596404, 643.1960049937578, 646.9454788694399, 649.9447546958467, 3803.54357477099, 4528.773561321921, 4552.453705555355, 4557.111363284128, 4622.930656040162, 96188.0, 126415.36792316164, 131994.34002829925, 164251.07246782616, 164389.17805411137, 138001.3099934494, 140968.5903140976, 141076.0, 141157.0, 145200.0, 104048.0, 105412.0, 106695.0, 108430.45784771183, 109894.35158472629, 8536.70121545743, 8726.65093396257, 8782.868256976279, 8810.163034511712, 8953.641854325753, 65180.0, 66072.66963665151, 66131.0, 69013.65493172502, 70014.0, 70738.58522829412, 78882.0, 81466.0, 89318.66022009806, 97000.0, 10886.61896833607, 13694.315284235548, 13721.451141954609, 13752.0, 14439.92780036093, 15439.536813895602, 15866.444674436802, 15884.682306353578, 16392.344306227616, 16419.58951026234, 14295.64260893486, 14416.783748243575, 14471.855281447471, 14603.675478288767, 14866.85133148698, 13982.930085349508, 14235.288235587972, 14573.271336433349, 14614.780778288121, 14791.088825497503, 13886.722265554834, 14106.2946852659, 14110.012299138962, 14501.709965800414, 14581.781273281122, 11557.942210288895, 11571.594994175164, 11731.885470880252, 11860.703482413008, 11988.76022479528, 8872.201501864864, 10838.211695070226, 11444.02725768319, 11519.76960460803, 11846.229995050295], "value": 8090.7052012418035, "unit": "score"}], "name": "dromaeo_css", "value": 7470.993068986117, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 118.0, 162.0, 166.0, 115.0, 112.0, 112.0, 113.0, 113.0, 117.0], "value": 123.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 105.0, 105.0, 170.0, 104.0, 106.0, 134.0, 104.0, 139.0, 106.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 189.0, 185.0, 184.0, 186.0, 184.0, 181.0, 185.0, 185.0, 183.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 58.0, 57.0, 105.0, 59.0, 79.0, 87.0, 94.0, 84.0, 57.0], "value": 75.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 67.0, 93.0, 74.0, 72.0, 70.0, 100.0, 69.0, 69.0, 103.0], "value": 80.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 87.0, 88.0, 86.0, 90.0, 86.0, 91.0, 88.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 106.0, 150.0, 111.0, 93.0, 93.0, 95.0, 95.0, 95.0], "value": 102.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 85.0, 85.0, 86.0, 91.0, 84.0, 86.0, 130.0], "value": 90.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 55.0, 55.0, 52.0, 53.0, 52.0, 52.0, 51.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 80.0, 78.0, 70.0, 69.0, 72.0, 73.0, 72.0, 73.0, 75.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 83.0, 83.0, 83.0, 82.0, 82.0, 86.0, 85.0, 81.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 139.0, 136.0, 131.0, 137.0, 141.0, 138.0, 210.0, 133.0], "value": 144.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 164.0, 163.0, 164.0, 163.0, 165.0, 167.0, 162.0], "value": 163.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 78.0, 86.0, 85.0, 69.0, 67.0, 67.0, 66.0, 66.0, 66.0], "value": 71.8, "unit": "score"}], "name": "kraken", "value": 1455.6, "alertThreshold": 2.0}]} 03:51:28 INFO - Return code: 0 03:51:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:51:28 INFO - # TBPL SUCCESS # 03:51:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:51:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:51:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:51:28 INFO - 2016-07-27 03:51:28.434 screenresolution[1973:19308] starting screenresolution argv=screenresolution get 03:51:28 INFO - 2016-07-27 03:51:28.437 screenresolution[1973:19308] Display 0: 1600x1200x32@50 03:51:28 INFO - 2016-07-27 03:51:28.444 screenresolution[1974:19311] starting screenresolution argv=screenresolution list 03:51:28 INFO - Available Modes on Display 0 03:51:28 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 03:51:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:51:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:51:28 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 03:51:28 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 03:51:28 INFO - Graphics/Displays: 03:51:28 INFO - Intel Iris: 03:51:28 INFO - Chipset Model: Intel Iris 03:51:28 INFO - Type: GPU 03:51:28 INFO - Bus: Built-In 03:51:28 INFO - VRAM (Dynamic, Max): 1536 MB 03:51:28 INFO - Vendor: Intel (0x8086) 03:51:28 INFO - Device ID: 0x0a2e 03:51:28 INFO - Revision ID: 0x0009 03:51:28 INFO - Displays: 03:51:28 INFO - Display: 03:51:28 INFO - Resolution: 1600 x 1200 @ 50 Hz 03:51:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:51:28 INFO - Display Serial Number: 1600x1200 50 03:51:28 INFO - Main Display: Yes 03:51:28 INFO - Mirror: Off 03:51:28 INFO - Online: Yes 03:51:28 INFO - Rotation: Supported 03:51:28 INFO - Return code: 0 03:51:28 INFO - Running post-action listener: _resource_record_post_action 03:51:28 INFO - [mozharness: 2016-07-27 10:51:28.682157Z] Finished run-tests step (success) 03:51:28 INFO - Running post-run listener: _resource_record_post_run 03:51:28 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 1503232; Write bytes: 704906752; Read time: 6; Write time: 1922 03:51:28 INFO - TinderboxPrint: CPU usage
53.5% 03:51:28 INFO - TinderboxPrint: I/O read bytes / time
1,503,232 / 6 03:51:28 INFO - TinderboxPrint: I/O write bytes / time
704,906,752 / 1,922 03:51:28 INFO - TinderboxPrint: CPU idle
2,256.3 (46.5%) 03:51:28 INFO - TinderboxPrint: CPU system
974.4 (20.1%) 03:51:28 INFO - TinderboxPrint: CPU user
1,623.0 (33.4%) 03:51:28 INFO - TinderboxPrint: Swap in / out
408,494,080 / 0 03:51:28 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 408463872; Write bytes: 410493952; Read time: 30845; Write time: 788 03:51:28 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 61440; Write bytes: 293200384; Read time: 1; Write time: 1128 03:51:29 INFO - Running post-run listener: _upload_blobber_files 03:51:29 INFO - Blob upload gear active. 03:51:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:51:29 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 03:51:29 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'] 03:51:29 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 03:51:29 INFO - (blobuploader) - INFO - Open directory for files ... 03:51:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:51:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:51:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:51:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:51:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:51:30 INFO - (blobuploader) - INFO - Done attempting. 03:51:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:51:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:51:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:51:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:51:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:51:31 INFO - (blobuploader) - INFO - Done attempting. 03:51:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:51:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:51:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:51:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:51:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:51:33 INFO - (blobuploader) - INFO - Done attempting. 03:51:33 INFO - (blobuploader) - INFO - Iteration through files over. 03:51:33 INFO - Return code: 0 03:51:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:51:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:51:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/79a0ebe9c111553944fa4b8a1f6969b33e4085666b29c0e200b42d24e96f927d64f6b4c612a523a27d032cddc5fd51644b639a39005cb50e8ed6bc5ef6df4c40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/60bb4844fd2edcf4c4434f2b5329ca2d1e4fed920579efaf3219617ab902a36846afa9d2c437d9bd08a3fd248f5f81adf16b5d7b5d83941797b7bbab2002dfa2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1bb0b0fccf614ed764274c864067b4d597b5a4997d8878867555a50a006384ab384d278d21a25d21e6ff2ba9c1532169e50ccc0a25203a5010b8d5fa93eeed44"} 03:51:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:51:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:51:33 INFO - Contents: 03:51:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/79a0ebe9c111553944fa4b8a1f6969b33e4085666b29c0e200b42d24e96f927d64f6b4c612a523a27d032cddc5fd51644b639a39005cb50e8ed6bc5ef6df4c40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/60bb4844fd2edcf4c4434f2b5329ca2d1e4fed920579efaf3219617ab902a36846afa9d2c437d9bd08a3fd248f5f81adf16b5d7b5d83941797b7bbab2002dfa2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1bb0b0fccf614ed764274c864067b4d597b5a4997d8878867555a50a006384ab384d278d21a25d21e6ff2ba9c1532169e50ccc0a25203a5010b8d5fa93eeed44"} 03:51:33 INFO - Running post-run listener: copy_logs_to_upload_dir 03:51:33 INFO - Copying logs to upload dir... 03:51:33 INFO - mkdir: /builds/slave/test/build/upload/logs 03:51:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.583655 ========= master_lag: 1.39 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-27 03:51:35.399292) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 03:51:35.403015) ========= 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.2wrSTncfwW/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.qYV5sk5pRc/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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/79a0ebe9c111553944fa4b8a1f6969b33e4085666b29c0e200b42d24e96f927d64f6b4c612a523a27d032cddc5fd51644b639a39005cb50e8ed6bc5ef6df4c40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/60bb4844fd2edcf4c4434f2b5329ca2d1e4fed920579efaf3219617ab902a36846afa9d2c437d9bd08a3fd248f5f81adf16b5d7b5d83941797b7bbab2002dfa2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1bb0b0fccf614ed764274c864067b4d597b5a4997d8878867555a50a006384ab384d278d21a25d21e6ff2ba9c1532169e50ccc0a25203a5010b8d5fa93eeed44"} build_url:https://queue.taskcluster.net/v1/task/PeSldl3CT4SzR7AfH_8CCA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011238 build_url: 'https://queue.taskcluster.net/v1/task/PeSldl3CT4SzR7AfH_8CCA/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/79a0ebe9c111553944fa4b8a1f6969b33e4085666b29c0e200b42d24e96f927d64f6b4c612a523a27d032cddc5fd51644b639a39005cb50e8ed6bc5ef6df4c40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/60bb4844fd2edcf4c4434f2b5329ca2d1e4fed920579efaf3219617ab902a36846afa9d2c437d9bd08a3fd248f5f81adf16b5d7b5d83941797b7bbab2002dfa2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1bb0b0fccf614ed764274c864067b4d597b5a4997d8878867555a50a006384ab384d278d21a25d21e6ff2ba9c1532169e50ccc0a25203a5010b8d5fa93eeed44"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 03:51:35.441296) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 03:51:35.441616) ========= 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.2wrSTncfwW/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.qYV5sk5pRc/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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.005147 ========= master_lag: 0.43 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 03:51:35.875441) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 03:51:35.875779) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 03:51:35.876092) ========= ========= Total master_lag: 1.92 =========