builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0064 starttime: 1468023882.55 results: success (0) buildid: 20160708150326 builduid: cb80233497f8473f92fce3d3b17d3618 revision: bbb29a9b88dd680dbb59577cbe4dc6e58d117100 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 17:24:42.550750) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 17:24:42.551230) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 17:24:42.551542) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 17:24:42.574150) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 17:24:42.574444) ========= 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.MTf4YgWEL8/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.cdqBYGlzie/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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-08 17:24:42-- 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% 8.22M=0.001s 2016-07-08 17:24:42 (8.22 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117070 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 17:24:42.713448) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 17:24:42.713766) ========= 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.MTf4YgWEL8/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.cdqBYGlzie/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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.020652 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 17:24:42.761922) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 17:24:42.762309) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bbb29a9b88dd680dbb59577cbe4dc6e58d117100 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev bbb29a9b88dd680dbb59577cbe4dc6e58d117100 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MTf4YgWEL8/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.cdqBYGlzie/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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-08 17:24:42,832 truncating revision to first 12 chars 2016-07-08 17:24:42,833 Setting DEBUG logging. 2016-07-08 17:24:42,833 attempt 1/10 2016-07-08 17:24:42,833 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bbb29a9b88dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-08 17:24:43,494 unpacking tar archive at: mozilla-central-bbb29a9b88dd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.915642 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 17:24:43.697837) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 17:24:43.698172) ========= script_repo_revision: bbb29a9b88dd680dbb59577cbe4dc6e58d117100 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 17:24:43.698554) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 17:24:43.698818) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 17:24:43.719965) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-08 17:24:43.720254) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MTf4YgWEL8/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.cdqBYGlzie/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 17:24:43 INFO - MultiFileLogger online at 20160708 17:24:43 in /builds/slave/test 17:24:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:24:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:24:43 INFO - {'append_to_log': False, 17:24:43 INFO - 'base_work_dir': '/builds/slave/test', 17:24:43 INFO - 'blob_upload_branch': 'Firefox', 17:24:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:24:43 INFO - 'branch': 'Firefox', 17:24:43 INFO - 'buildbot_json_path': 'buildprops.json', 17:24:43 INFO - 'config_files': ('talos/mac_config.py',), 17:24:43 INFO - 'default_actions': ('clobber', 17:24:43 INFO - 'read-buildbot-config', 17:24:43 INFO - 'download-and-extract', 17:24:43 INFO - 'populate-webroot', 17:24:43 INFO - 'create-virtualenv', 17:24:43 INFO - 'install', 17:24:43 INFO - 'run-tests'), 17:24:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:24:43 INFO - 'download_minidump_stackwalk': True, 17:24:43 INFO - 'download_symbols': 'ondemand', 17:24:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:24:43 INFO - 'tooltool.py': '/tools/tooltool.py', 17:24:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:24:43 INFO - '/tools/misc-python/virtualenv.py')}, 17:24:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:24:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:24:43 INFO - 'installer_path': 'installer.exe', 17:24:43 INFO - 'log_level': 'info', 17:24:43 INFO - 'log_name': 'talos', 17:24:43 INFO - 'log_to_console': True, 17:24:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:24:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:24:43 INFO - 'opt_config_files': (), 17:24:43 INFO - 'pip_index': False, 17:24:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:24:43 INFO - 'cmd': ('bash', 17:24:43 INFO - '-c', 17:24:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:24:43 INFO - 'enabled': True, 17:24:43 INFO - 'halt_on_failure': False, 17:24:43 INFO - 'name': 'check_screen_resolution'},), 17:24:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:24:43 INFO - 'cmd': ('bash', 17:24:43 INFO - '-c', 17:24:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:24:43 INFO - 'enabled': True, 17:24:43 INFO - 'halt_on_failure': False, 17:24:43 INFO - 'name': 'check_screen_resolution'},), 17:24:43 INFO - 'run_cmd_checks_enabled': True, 17:24:43 INFO - 'sps_profile': False, 17:24:43 INFO - 'sps_profile_interval': 0, 17:24:43 INFO - 'suite': 'dromaeojs', 17:24:43 INFO - 'system_bits': '32', 17:24:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:24:43 INFO - 'title': 't-yosemite-r7-0064', 17:24:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:24:43 INFO - 'use_talos_json': True, 17:24:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:24:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:24:43 INFO - 'work_dir': 'build'} 17:24:43 INFO - ##### 17:24:43 INFO - ##### Running clobber step. 17:24:43 INFO - ##### 17:24:43 INFO - Running pre-action listener: _resource_record_pre_action 17:24:43 INFO - Running main action method: clobber 17:24:43 INFO - rmtree: /builds/slave/test/build 17:24:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:24:45 INFO - Running post-action listener: _resource_record_post_action 17:24:45 INFO - ##### 17:24:45 INFO - ##### Finished clobber step (success) 17:24:45 INFO - ##### 17:24:45 INFO - ##### 17:24:45 INFO - ##### Running read-buildbot-config step. 17:24:45 INFO - ##### 17:24:45 INFO - Running pre-action listener: _resource_record_pre_action 17:24:45 INFO - Running main action method: read_buildbot_config 17:24:45 INFO - Using buildbot properties: 17:24:45 INFO - { 17:24:45 INFO - "project": "", 17:24:45 INFO - "product": "firefox", 17:24:45 INFO - "script_repo_revision": "production", 17:24:45 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 17:24:45 INFO - "repository": "", 17:24:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 17:24:45 INFO - "buildid": "20160708150326", 17:24:45 INFO - "pgo_build": "False", 17:24:45 INFO - "basedir": "/builds/slave/test", 17:24:45 INFO - "buildnumber": 288, 17:24:45 INFO - "slavename": "t-yosemite-r7-0064", 17:24:45 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:24:45 INFO - "platform": "yosemite_r7", 17:24:45 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:24:45 INFO - "branch": "mozilla-central", 17:24:45 INFO - "repo_path": "mozilla-central", 17:24:45 INFO - "revision": "bbb29a9b88dd680dbb59577cbe4dc6e58d117100", 17:24:45 INFO - "stage_platform": "macosx64", 17:24:45 INFO - "builduid": "cb80233497f8473f92fce3d3b17d3618", 17:24:45 INFO - "slavebuilddir": "test" 17:24:45 INFO - } 17:24:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 17:24:45 INFO - Running post-action listener: _resource_record_post_action 17:24:45 INFO - ##### 17:24:45 INFO - ##### Finished read-buildbot-config step (success) 17:24:45 INFO - ##### 17:24:45 INFO - ##### 17:24:45 INFO - ##### Running download-and-extract step. 17:24:45 INFO - ##### 17:24:45 INFO - Running pre-action listener: _resource_record_pre_action 17:24:45 INFO - Running main action method: download_and_extract 17:24:45 INFO - mkdir: /builds/slave/test/build/tests 17:24:45 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')]} 17:24:45 INFO - https://queue.taskcluster.net/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:24:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 17:24:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 17:24:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/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 17:24:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/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 17:24:46 INFO - Downloaded 1199 bytes. 17:24:46 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 17:24:46 INFO - Using the following test package requirements: 17:24:46 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 17:24:46 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:24:46 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 17:24:46 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:24:46 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 17:24:46 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:24:46 INFO - u'jsshell-mac.zip'], 17:24:46 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:24:46 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 17:24:46 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 17:24:46 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:24:46 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 17:24:46 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:24:46 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 17:24:46 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:24:46 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 17:24:46 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:24:46 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 17:24:46 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:24:46 INFO - https://queue.taskcluster.net/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:24:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 17:24:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 17:24:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/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 17:24:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/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 17:24:47 INFO - Downloaded 18807051 bytes. 17:24:47 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'] 17:24:47 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 17:24:51 INFO - Return code: 0 17:24:51 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 17:24:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:24:51 INFO - https://queue.taskcluster.net/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:24:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 17:24:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 17:24:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/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 17:24:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/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 17:24:52 INFO - Downloaded 18807051 bytes. 17:24:52 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'] 17:24:52 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 17:24:55 INFO - Return code: 0 17:24: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')]} 17:24:55 INFO - https://queue.taskcluster.net/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:24:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 17:24:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 17:24:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/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 17:24:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/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 17:24:56 INFO - Downloaded 14403333 bytes. 17:24:56 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'] 17:24:56 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 17:24:57 INFO - Return code: 0 17:24:57 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')]} 17:24:57 INFO - https://queue.taskcluster.net/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 17:24:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 17:24:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 17:24:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 17:24:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A0txUtkLRq67XdDXxuZfGw/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 17:25:00 INFO - Downloaded 112490487 bytes. 17:25:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 17:25:00 INFO - mkdir: /builds/slave/test/properties 17:25:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:25:00 INFO - Writing to file /builds/slave/test/properties/build_url 17:25:00 INFO - Contents: 17:25:00 INFO - build_url:https://queue.taskcluster.net/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 17:25:01 INFO - Running post-action listener: _resource_record_post_action 17:25:01 INFO - Running post-action listener: set_extra_try_arguments 17:25:01 INFO - ##### 17:25:01 INFO - ##### Finished download-and-extract step (success) 17:25:01 INFO - ##### 17:25:01 INFO - ##### 17:25:01 INFO - ##### Running populate-webroot step. 17:25:01 INFO - ##### 17:25:01 INFO - Running pre-action listener: _resource_record_pre_action 17:25:01 INFO - Running main action method: populate_webroot 17:25:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:25:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:25:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:25:01 INFO - '--tppagecycles', 17:25:01 INFO - '7'], 17:25:01 INFO - 'tests': ['tsvgm']}}, 17:25:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:25:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:25:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:25:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:25:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:25:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:25:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:25:01 INFO - 'talos_options': ['--disable-e10s'], 17:25:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:25:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:25:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:25:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:25:01 INFO - 'talos_options': ['--disable-e10s'], 17:25:01 INFO - 'tests': ['damp', 'tps']}, 17:25:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:25:01 INFO - 'tests': ['damp', 'tps']}, 17:25:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:25:01 INFO - 'tests': ['dromaeo_dom']}, 17:25:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:25:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:25:01 INFO - 'tests': ['basic_compositor_video']}, 17:25:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:25:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:25:01 INFO - 'tests': ['a11yr', 17:25:01 INFO - 'ts_paint', 17:25:01 INFO - 'tpaint', 17:25:01 INFO - 'sessionrestore', 17:25:01 INFO - 'sessionrestore_no_auto_restore', 17:25:01 INFO - 'tabpaint']}, 17:25:01 INFO - 'other-e10s': {'tests': ['a11yr', 17:25:01 INFO - 'ts_paint', 17:25:01 INFO - 'tpaint', 17:25:01 INFO - 'sessionrestore', 17:25:01 INFO - 'sessionrestore_no_auto_restore', 17:25:01 INFO - 'tabpaint']}, 17:25:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:25:01 INFO - 'ts_paint', 17:25:01 INFO - 'tpaint', 17:25:01 INFO - 'sessionrestore', 17:25:01 INFO - 'sessionrestore_no_auto_restore', 17:25:01 INFO - 'tabpaint']}, 17:25:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:25:01 INFO - 'ts_paint', 17:25:01 INFO - 'tpaint', 17:25:01 INFO - 'sessionrestore', 17:25:01 INFO - 'sessionrestore_no_auto_restore', 17:25:01 INFO - 'tabpaint']}, 17:25:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:25:01 INFO - 'tests': ['a11yr', 17:25:01 INFO - 'ts_paint', 17:25:01 INFO - 'tpaint', 17:25:01 INFO - 'sessionrestore', 17:25:01 INFO - 'sessionrestore_no_auto_restore', 17:25:01 INFO - 'tabpaint']}, 17:25:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:25:01 INFO - 'tests': ['a11yr', 17:25:01 INFO - 'ts_paint', 17:25:01 INFO - 'tpaint', 17:25:01 INFO - 'sessionrestore', 17:25:01 INFO - 'sessionrestore_no_auto_restore', 17:25:01 INFO - 'tabpaint']}, 17:25:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:25:01 INFO - 'tests': ['tsvgx', 17:25:01 INFO - 'tsvgr_opacity', 17:25:01 INFO - 'tart', 17:25:01 INFO - 'tscrollx', 17:25:01 INFO - 'cart']}, 17:25:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:25:01 INFO - 'tsvgr_opacity', 17:25:01 INFO - 'tart', 17:25:01 INFO - 'tscrollx', 17:25:01 INFO - 'cart']}, 17:25:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:25:01 INFO - 'talos_options': ['--disable-e10s'], 17:25:01 INFO - 'tests': ['tp5o']}, 17:25:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:25:01 INFO - 'tests': ['tp5o']}, 17:25:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:25:01 INFO - 'talos_options': ['--disable-e10s', 17:25:01 INFO - '--xperf_path', 17:25:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:25:01 INFO - 'tests': ['tp5n']}, 17:25:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:25:01 INFO - 'talos_options': ['--xperf_path', 17:25:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:25:01 INFO - 'tests': ['tp5n']}}, 17:25:01 INFO - 'talos.zip': {'path': '', 17:25:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:25:01 INFO - Running post-action listener: _resource_record_post_action 17:25:01 INFO - ##### 17:25:01 INFO - ##### Finished populate-webroot step (success) 17:25:01 INFO - ##### 17:25:01 INFO - ##### 17:25:01 INFO - ##### Running create-virtualenv step. 17:25:01 INFO - ##### 17:25:01 INFO - Running pre-action listener: _resource_record_pre_action 17:25:01 INFO - Running main action method: create_virtualenv 17:25:01 INFO - Creating virtualenv /builds/slave/test/build/venv 17:25:01 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 17:25:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:25:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:25:01 INFO - Using real prefix '/tools/python27' 17:25:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:25:02 INFO - Installing distribute.............................................................................................................................................................................................done. 17:25:05 INFO - Installing pip.................done. 17:25:05 INFO - Return code: 0 17:25:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:05 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 0x10c30fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713cc0540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c273d30>, '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 0x10bfd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c26bb20>, '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.MTf4YgWEL8/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.cdqBYGlzie/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 17:25:05 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 17:25:05 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 17:25:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTf4YgWEL8/Render', 17:25:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:05 INFO - 'HOME': '/Users/cltbld', 17:25:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:05 INFO - 'LOGNAME': 'cltbld', 17:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:05 INFO - 'MOZ_NO_REMOTE': '1', 17:25:05 INFO - 'NO_EM_RESTART': '1', 17:25:05 INFO - 'PAGER': '/bin/cat', 17:25:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:05 INFO - 'PWD': '/builds/slave/test', 17:25:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:05 INFO - 'SHELL': '/bin/bash', 17:25:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cdqBYGlzie/Listeners', 17:25:05 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 17:25:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:05 INFO - 'USER': 'cltbld', 17:25:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:05 INFO - 'XPC_FLAGS': '0x0', 17:25:05 INFO - 'XPC_SERVICE_NAME': '0', 17:25:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:05 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 17:25:05 INFO - Cleaning up... 17:25:05 INFO - Return code: 0 17:25:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:05 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 0x10c30fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713cc0540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c273d30>, '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 0x10bfd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c26bb20>, '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.MTf4YgWEL8/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.cdqBYGlzie/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 17:25:05 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 17:25:05 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 17:25:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTf4YgWEL8/Render', 17:25:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:05 INFO - 'HOME': '/Users/cltbld', 17:25:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:05 INFO - 'LOGNAME': 'cltbld', 17:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:05 INFO - 'MOZ_NO_REMOTE': '1', 17:25:05 INFO - 'NO_EM_RESTART': '1', 17:25:05 INFO - 'PAGER': '/bin/cat', 17:25:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:05 INFO - 'PWD': '/builds/slave/test', 17:25:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:05 INFO - 'SHELL': '/bin/bash', 17:25:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cdqBYGlzie/Listeners', 17:25:05 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 17:25:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:05 INFO - 'USER': 'cltbld', 17:25:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:05 INFO - 'XPC_FLAGS': '0x0', 17:25:05 INFO - 'XPC_SERVICE_NAME': '0', 17:25:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:05 INFO - Downloading/unpacking psutil>=3.1.1 17:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:25:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:25:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:25:08 INFO - Installing collected packages: psutil 17:25:08 INFO - Running setup.py install for psutil 17:25:08 INFO - building 'psutil._psutil_osx' extension 17:25:08 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 17:25:09 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 17:25:09 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 17:25:09 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 17:25:09 INFO - building 'psutil._psutil_posix' extension 17:25:09 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 17:25:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:25:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:25:09 INFO - ^ 17:25:09 INFO - 1 warning generated. 17:25:09 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 17:25:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:25:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:25:09 INFO - Successfully installed psutil 17:25:09 INFO - Cleaning up... 17:25:09 INFO - Return code: 0 17:25:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:25:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:09 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 0x10c30fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713cc0540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c273d30>, '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 0x10bfd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c26bb20>, '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.MTf4YgWEL8/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.cdqBYGlzie/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 17:25:09 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 17:25:09 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 17:25:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTf4YgWEL8/Render', 17:25:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:09 INFO - 'HOME': '/Users/cltbld', 17:25:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:09 INFO - 'LOGNAME': 'cltbld', 17:25:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:09 INFO - 'MOZ_NO_REMOTE': '1', 17:25:09 INFO - 'NO_EM_RESTART': '1', 17:25:09 INFO - 'PAGER': '/bin/cat', 17:25:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:09 INFO - 'PWD': '/builds/slave/test', 17:25:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:09 INFO - 'SHELL': '/bin/bash', 17:25:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cdqBYGlzie/Listeners', 17:25:09 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 17:25:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:09 INFO - 'USER': 'cltbld', 17:25:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:09 INFO - 'XPC_FLAGS': '0x0', 17:25:09 INFO - 'XPC_SERVICE_NAME': '0', 17:25:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:25:09 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 17:25:09 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 17:25:09 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 17:25:09 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 17:25:09 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 17:25:09 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 17:25:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:25:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:25:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:25:12 INFO - Installing collected packages: mozsystemmonitor 17:25:12 INFO - Running setup.py install for mozsystemmonitor 17:25:12 INFO - Successfully installed mozsystemmonitor 17:25:12 INFO - Cleaning up... 17:25:12 INFO - Return code: 0 17:25:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:25:12 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')]} 17:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:12 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')]} 17:25:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:12 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 0x10c30fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713cc0540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c273d30>, '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 0x10bfd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c26bb20>, '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.MTf4YgWEL8/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.cdqBYGlzie/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 17:25:12 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 17:25:12 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 17:25:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTf4YgWEL8/Render', 17:25:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:12 INFO - 'HOME': '/Users/cltbld', 17:25:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:12 INFO - 'LOGNAME': 'cltbld', 17:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:12 INFO - 'MOZ_NO_REMOTE': '1', 17:25:12 INFO - 'NO_EM_RESTART': '1', 17:25:12 INFO - 'PAGER': '/bin/cat', 17:25:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:12 INFO - 'PWD': '/builds/slave/test', 17:25:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:12 INFO - 'SHELL': '/bin/bash', 17:25:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cdqBYGlzie/Listeners', 17:25:12 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 17:25:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:12 INFO - 'USER': 'cltbld', 17:25:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:12 INFO - 'XPC_FLAGS': '0x0', 17:25:12 INFO - 'XPC_SERVICE_NAME': '0', 17:25:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:12 INFO - Downloading/unpacking jsonschema==2.5.1 17:25:12 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 17:25:12 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 17:25:12 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 17:25:12 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 17:25:12 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 17:25:12 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 17:25:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:25:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:25:15 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 17:25:15 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 17:25:15 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 17:25:15 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 17:25:15 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 17:25:15 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 17:25:15 INFO - Downloading functools32-3.2.3-2.tar.gz 17:25:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:25:15 INFO - warning: no files found matching '*.txt' 17:25:15 INFO - no previously-included directories found matching 'build' 17:25:15 INFO - no previously-included directories found matching 'dist' 17:25:15 INFO - no previously-included directories found matching '.git*' 17:25:15 INFO - Installing collected packages: jsonschema, functools32 17:25:15 INFO - Running setup.py install for functools32 17:25:16 INFO - warning: no files found matching '*.txt' 17:25:16 INFO - no previously-included directories found matching 'build' 17:25:16 INFO - no previously-included directories found matching 'dist' 17:25:16 INFO - no previously-included directories found matching '.git*' 17:25:16 INFO - Successfully installed jsonschema functools32 17:25:16 INFO - Cleaning up... 17:25:16 INFO - Return code: 0 17:25:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:25:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:16 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 0x10c30fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713cc0540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c273d30>, '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 0x10bfd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c26bb20>, '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.MTf4YgWEL8/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.cdqBYGlzie/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 17:25:16 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 17:25:16 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 17:25:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTf4YgWEL8/Render', 17:25:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:16 INFO - 'HOME': '/Users/cltbld', 17:25:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:16 INFO - 'LOGNAME': 'cltbld', 17:25:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:16 INFO - 'MOZ_NO_REMOTE': '1', 17:25:16 INFO - 'NO_EM_RESTART': '1', 17:25:16 INFO - 'PAGER': '/bin/cat', 17:25:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:16 INFO - 'PWD': '/builds/slave/test', 17:25:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:16 INFO - 'SHELL': '/bin/bash', 17:25:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cdqBYGlzie/Listeners', 17:25:16 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 17:25:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:16 INFO - 'USER': 'cltbld', 17:25:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:16 INFO - 'XPC_FLAGS': '0x0', 17:25:16 INFO - 'XPC_SERVICE_NAME': '0', 17:25:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:16 INFO - Downloading/unpacking blobuploader==1.2.4 17:25:16 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 17:25:16 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 17:25:16 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 17:25:16 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 17:25:16 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 17:25:16 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 17:25:18 INFO - Downloading blobuploader-1.2.4.tar.gz 17:25:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:25:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:25:19 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 17:25:19 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 17:25:19 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 17:25:19 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 17:25:19 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 17:25:19 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 17:25:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:25:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:25:19 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 17:25:19 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 17:25:19 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 17:25:19 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 17:25:19 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 17:25:19 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 17:25:20 INFO - Downloading docopt-0.6.1.tar.gz 17:25:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:25:20 INFO - Installing collected packages: blobuploader, requests, docopt 17:25:20 INFO - Running setup.py install for blobuploader 17:25:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:25:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:25:20 INFO - Running setup.py install for requests 17:25:20 INFO - Running setup.py install for docopt 17:25:20 INFO - Successfully installed blobuploader requests docopt 17:25:20 INFO - Cleaning up... 17:25:20 INFO - Return code: 0 17:25:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25:21 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')]} 17:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:21 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')]} 17:25:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:21 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 0x10c30fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713cc0540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c273d30>, '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 0x10bfd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c26bb20>, '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.MTf4YgWEL8/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.cdqBYGlzie/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 17:25:21 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 17:25:21 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 17:25:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTf4YgWEL8/Render', 17:25:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:21 INFO - 'HOME': '/Users/cltbld', 17:25:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:21 INFO - 'LOGNAME': 'cltbld', 17:25:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:21 INFO - 'MOZ_NO_REMOTE': '1', 17:25:21 INFO - 'NO_EM_RESTART': '1', 17:25:21 INFO - 'PAGER': '/bin/cat', 17:25:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:21 INFO - 'PWD': '/builds/slave/test', 17:25:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:21 INFO - 'SHELL': '/bin/bash', 17:25:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cdqBYGlzie/Listeners', 17:25:21 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 17:25:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:21 INFO - 'USER': 'cltbld', 17:25:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:21 INFO - 'XPC_FLAGS': '0x0', 17:25:21 INFO - 'XPC_SERVICE_NAME': '0', 17:25:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:25:21 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-dPJ_qT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:25:21 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-6rvwzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:25:21 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-J5d_G5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:25:21 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-YVVl46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:25:21 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-5Haaqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:25:21 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-eycEDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:25:21 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-HnQ6AH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:25:21 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-4qLUva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:25:22 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-jUS9i3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:25:22 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-nNnVvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:25:22 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-gfjXCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:25:22 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-xjdW9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:25:22 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-Qxilif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:25:22 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-FvfV3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:22 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-nWWgOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:25:22 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-MyMmj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:25:22 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-bjmDAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:25:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:25:23 INFO - Running setup.py install for manifestparser 17:25:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:25:23 INFO - Running setup.py install for mozcrash 17:25:23 INFO - Running setup.py install for mozdebug 17:25:23 INFO - Running setup.py install for mozdevice 17:25:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:25:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:25:23 INFO - Running setup.py install for mozfile 17:25:23 INFO - Running setup.py install for mozhttpd 17:25:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:25:23 INFO - Running setup.py install for mozinfo 17:25:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:25:24 INFO - Running setup.py install for mozInstall 17:25:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:25:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:25:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:25:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:25:24 INFO - Running setup.py install for mozleak 17:25:24 INFO - Running setup.py install for mozlog 17:25:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:25:24 INFO - Running setup.py install for moznetwork 17:25:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:25:24 INFO - Running setup.py install for mozprocess 17:25:24 INFO - Running setup.py install for mozprofile 17:25:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:25:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:25:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:25:25 INFO - Running setup.py install for mozrunner 17:25:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:25:25 INFO - Running setup.py install for mozscreenshot 17:25:25 INFO - Running setup.py install for moztest 17:25:25 INFO - Running setup.py install for mozversion 17:25:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:25:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:25:25 INFO - Cleaning up... 17:25:25 INFO - Return code: 0 17:25:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:25 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 0x10c30fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713cc0540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c273d30>, '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 0x10bfd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c26bb20>, '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.MTf4YgWEL8/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.cdqBYGlzie/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 17:25:25 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 17:25:25 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 17:25:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTf4YgWEL8/Render', 17:25:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:25 INFO - 'HOME': '/Users/cltbld', 17:25:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:25 INFO - 'LOGNAME': 'cltbld', 17:25:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:25 INFO - 'MOZ_NO_REMOTE': '1', 17:25:25 INFO - 'NO_EM_RESTART': '1', 17:25:25 INFO - 'PAGER': '/bin/cat', 17:25:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:25 INFO - 'PWD': '/builds/slave/test', 17:25:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:25 INFO - 'SHELL': '/bin/bash', 17:25:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cdqBYGlzie/Listeners', 17:25:25 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 17:25:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:25 INFO - 'USER': 'cltbld', 17:25:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:25 INFO - 'XPC_FLAGS': '0x0', 17:25:25 INFO - 'XPC_SERVICE_NAME': '0', 17:25:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:25:25 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-17g8KK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:25:26 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)) 17:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:25:26 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-KM3kmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:25:26 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)) 17:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:25:26 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-SM6F8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:25:26 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)) 17:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:25:26 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-GtcyHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:25:26 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)) 17:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:25:26 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-qEgX44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:25:26 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)) 17:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:25:26 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-8oMUi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:25:26 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)) 17:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:25:26 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-IQ5UZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:25:26 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)) 17:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:25:26 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-nP8INY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:25:26 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)) 17:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:25:26 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-eAu052-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:25:26 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)) 17:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:25:26 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-QGYTHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:25:26 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-xdi3Y5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:25:27 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)) 17:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:25:27 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-9gDvzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:25:27 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)) 17:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:25:27 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-LE8lW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:25:27 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)) 17:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:25:27 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-1WnUpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:25:27 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)) 17:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:27 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-48_ZVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:27 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)) 17:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:25:27 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-tHESxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:25:27 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)) 17:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:25:27 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-oc0kbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:25:27 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)) 17:25:27 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)) 17:25:27 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)) 17:25:27 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)) 17:25:27 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)) 17:25:27 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)) 17:25:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:25:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:30 INFO - Downloading blessings-1.6.tar.gz 17:25:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:25:30 INFO - Installing collected packages: blessings 17:25:30 INFO - Running setup.py install for blessings 17:25:30 INFO - Successfully installed blessings 17:25:30 INFO - Cleaning up... 17:25:30 INFO - Return code: 0 17:25:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:25:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:25:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:25:30 INFO - Reading from file tmpfile_stdout 17:25:30 INFO - Current package versions: 17:25:30 INFO - blessings == 1.6 17:25:30 INFO - blobuploader == 1.2.4 17:25:30 INFO - docopt == 0.6.1 17:25:30 INFO - functools32 == 3.2.3-2 17:25:30 INFO - jsonschema == 2.5.1 17:25:30 INFO - manifestparser == 1.1 17:25:30 INFO - mozInstall == 1.12 17:25:30 INFO - mozcrash == 0.17 17:25:30 INFO - mozdebug == 0.1 17:25:30 INFO - mozdevice == 0.48 17:25:30 INFO - mozfile == 1.2 17:25:30 INFO - mozhttpd == 0.7 17:25:30 INFO - mozinfo == 0.9 17:25:30 INFO - mozleak == 0.1 17:25:30 INFO - mozlog == 3.2 17:25:30 INFO - moznetwork == 0.27 17:25:30 INFO - mozprocess == 0.23 17:25:30 INFO - mozprofile == 0.28 17:25:30 INFO - mozrunner == 6.12 17:25:30 INFO - mozscreenshot == 0.1 17:25:30 INFO - mozsystemmonitor == 0.3 17:25:30 INFO - moztest == 0.7 17:25:30 INFO - mozversion == 1.4 17:25:30 INFO - psutil == 3.1.1 17:25:30 INFO - requests == 1.2.3 17:25:30 INFO - wsgiref == 0.1.2 17:25:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:30 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 0x10c30fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713cc0540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c273d30>, '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 0x10bfd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c26bb20>, '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.MTf4YgWEL8/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.cdqBYGlzie/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 17:25:30 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 17:25:30 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 17:25:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTf4YgWEL8/Render', 17:25:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:30 INFO - 'HOME': '/Users/cltbld', 17:25:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:30 INFO - 'LOGNAME': 'cltbld', 17:25:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:30 INFO - 'MOZ_NO_REMOTE': '1', 17:25:30 INFO - 'NO_EM_RESTART': '1', 17:25:30 INFO - 'PAGER': '/bin/cat', 17:25:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:30 INFO - 'PWD': '/builds/slave/test', 17:25:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:30 INFO - 'SHELL': '/bin/bash', 17:25:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cdqBYGlzie/Listeners', 17:25:30 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 17:25:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:30 INFO - 'USER': 'cltbld', 17:25:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:30 INFO - 'XPC_FLAGS': '0x0', 17:25:30 INFO - 'XPC_SERVICE_NAME': '0', 17:25:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:31 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)) 17:25:31 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)) 17:25:31 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)) 17:25:31 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)) 17:25:31 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)) 17:25:31 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)) 17:25:31 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)) 17:25:31 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)) 17:25:31 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)) 17:25:31 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)) 17:25:31 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)) 17:25:31 INFO - Cleaning up... 17:25:31 INFO - Return code: 0 17:25:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:25: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')]} 17:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25: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')]} 17:25:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:31 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 0x10c30fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713cc0540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c273d30>, '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 0x10bfd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c26bb20>, '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.MTf4YgWEL8/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.cdqBYGlzie/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 17:25:31 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 17:25:31 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 17:25:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTf4YgWEL8/Render', 17:25:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:31 INFO - 'HOME': '/Users/cltbld', 17:25:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:31 INFO - 'LOGNAME': 'cltbld', 17:25:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:31 INFO - 'MOZ_NO_REMOTE': '1', 17:25:31 INFO - 'NO_EM_RESTART': '1', 17:25:31 INFO - 'PAGER': '/bin/cat', 17:25:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:31 INFO - 'PWD': '/builds/slave/test', 17:25:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:31 INFO - 'SHELL': '/bin/bash', 17:25:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cdqBYGlzie/Listeners', 17:25:31 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 17:25:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:31 INFO - 'USER': 'cltbld', 17:25:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:31 INFO - 'XPC_FLAGS': '0x0', 17:25:31 INFO - 'XPC_SERVICE_NAME': '0', 17:25:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:25:31 INFO - Cleaning up... 17:25:31 INFO - Return code: 0 17:25:31 INFO - Running post-action listener: _resource_record_post_action 17:25:31 INFO - Running post-action listener: _start_resource_monitoring 17:25:31 INFO - Starting resource monitoring. 17:25:31 INFO - ##### 17:25:31 INFO - ##### Finished create-virtualenv step (success) 17:25:31 INFO - ##### 17:25:31 INFO - ##### 17:25:31 INFO - ##### Running install step. 17:25:31 INFO - ##### 17:25:31 INFO - Running pre-action listener: _resource_record_pre_action 17:25:31 INFO - Running main action method: install 17:25:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:25:31 INFO - Reading from file tmpfile_stdout 17:25:31 INFO - Detecting whether we're running mozinstall >=1.0... 17:25:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:25:31 INFO - Reading from file tmpfile_stdout 17:25:31 INFO - Output received: 17:25:31 INFO - Usage: mozinstall [options] installer 17:25:31 INFO - Options: 17:25:31 INFO - -h, --help show this help message and exit 17:25:31 INFO - -d DEST, --destination=DEST 17:25:31 INFO - Directory to install application into. [default: 17:25:31 INFO - "/builds/slave/test"] 17:25:31 INFO - --app=APP Application being installed. [default: firefox] 17:25:31 INFO - mkdir: /builds/slave/test/build/application 17:25:31 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'] 17:25:31 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 17:26:06 INFO - Reading from file tmpfile_stdout 17:26:06 INFO - Output received: 17:26:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:26:06 INFO - Running post-action listener: _resource_record_post_action 17:26:06 INFO - ##### 17:26:06 INFO - ##### Finished install step (success) 17:26:06 INFO - ##### 17:26:06 INFO - ##### 17:26:06 INFO - ##### Running run-tests step. 17:26:06 INFO - ##### 17:26:06 INFO - Running pre-action listener: _resource_record_pre_action 17:26:06 INFO - Running main action method: run_tests 17:26:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:26:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:26:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:26:06 INFO - 2016-07-08 17:26:06.262 screenresolution[1913:12529] starting screenresolution argv=screenresolution get 17:26:06 INFO - 2016-07-08 17:26:06.265 screenresolution[1913:12529] Display 0: 1600x1200x32@60 17:26:06 INFO - 2016-07-08 17:26:06.270 screenresolution[1914:12531] starting screenresolution argv=screenresolution list 17:26:06 INFO - Available Modes on Display 0 17:26:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:26:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:26:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:26:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:26:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:26:06 INFO - Intel Iris: 17:26:06 INFO - Chipset Model: Intel Iris 17:26:06 INFO - Type: GPU 17:26:06 INFO - Bus: Built-In 17:26:06 INFO - VRAM (Dynamic, Max): 1536 MB 17:26:06 INFO - Vendor: Intel (0x8086) 17:26:06 INFO - Device ID: 0x0a2e 17:26:06 INFO - Revision ID: 0x0009 17:26:06 INFO - Displays: 17:26:06 INFO - Display: 17:26:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:26:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:26:06 INFO - Display Serial Number: 1600x1200 60 17:26:06 INFO - Main Display: Yes 17:26:06 INFO - Mirror: Off 17:26:06 INFO - Online: Yes 17:26:06 INFO - Rotation: Supported 17:26:06 INFO - Return code: 0 17:26:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:26:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:26:06 INFO - Python 2.7.3 17:26:06 INFO - Return code: 0 17:26:06 INFO - grabbing minidump binary from tooltool 17:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:26:06 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 0x10c273d30>, '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 0x10bfd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c26bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:26:06 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 17:26:06 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 17:26:06 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 17:26:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:26:06 INFO - Return code: 0 17:26:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:26:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:26:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:26:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:26:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:26:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0064', '--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 17:26:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0064 --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 17:26:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MTf4YgWEL8/Render', 17:26:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:06 INFO - 'HOME': '/Users/cltbld', 17:26:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:06 INFO - 'LOGNAME': 'cltbld', 17:26:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:26:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:26:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:06 INFO - 'MOZ_NO_REMOTE': '1', 17:26:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:26:06 INFO - 'NO_EM_RESTART': '1', 17:26:06 INFO - 'PAGER': '/bin/cat', 17:26:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:06 INFO - 'PWD': '/builds/slave/test', 17:26:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:26:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:06 INFO - 'SHELL': '/bin/bash', 17:26:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cdqBYGlzie/Listeners', 17:26:06 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 17:26:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:06 INFO - 'USER': 'cltbld', 17:26:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:06 INFO - 'XPC_FLAGS': '0x0', 17:26:06 INFO - 'XPC_SERVICE_NAME': '0', 17:26:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0064', '--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 17:26:06 INFO - mozversion application_buildid: 20160708150326 17:26:06 INFO - mozversion application_changeset: bbb29a9b88dd680dbb59577cbe4dc6e58d117100 17:26:06 INFO - mozversion application_display_name: Nightly 17:26:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:26:06 INFO - mozversion application_name: Firefox 17:26:06 INFO - mozversion application_remotingname: firefox 17:26:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:26:06 INFO - mozversion application_vendor: Mozilla 17:26:06 INFO - mozversion application_version: 50.0a1 17:26:06 INFO - mozversion platform_buildid: 20160708150326 17:26:06 INFO - mozversion platform_changeset: bbb29a9b88dd680dbb59577cbe4dc6e58d117100 17:26:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:26:06 INFO - mozversion platform_version: 50.0a1 17:26:06 INFO - using testdate: 1468023966 17:26:06 INFO - actual date: 1468023966 17:26:06 INFO - starting webserver on 'localhost:50895' 17:26:06 INFO - SUITE-START | Running 2 tests 17:26:06 INFO - TEST-START | dromaeo_css 17:26:06 INFO - operating with platform_type : mac_ 17:26:06 INFO - Initialising browser for dromaeo_css test... 17:26:06 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmp5aUqL9/profile http://localhost:50895/getInfo.html) 17:26:08 INFO - PROCESS | 1924 | __metrics Screen width/height:1600/1200 17:26:08 INFO - PROCESS | 1924 | colorDepth:24 17:26:08 INFO - PROCESS | 1924 | Browser inner width/height: 1024/689 17:26:08 INFO - PROCESS | 1924 | __metrics 17:26:09 INFO - PROCESS | 1924 | 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" 17:26:09 INFO - PROCESS | 1924 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:26:09 INFO - TEST-INFO | 1924: exit 0 17:26:09 INFO - Browser initialized. 17:26:09 INFO - Running cycle 1/1 for dromaeo_css test... 17:26:09 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmp5aUqL9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:26:21 INFO - PROCESS | 1926 | RSS: Main: 243474432 17:26:21 INFO - PROCESS | 1926 | 17:27:13 INFO - PROCESS | 1926 | 1468024033662 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] 17:29:24 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:50895/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50895/tests/dromaeo/cssquery-ext.html) 17:29:25 INFO - PROCESS | 1926 | RSS: Main: 361021440 17:29:25 INFO - PROCESS | 1926 | 17:32:29 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:50895/tests/dromaeo/cssquery-ext.html (next: http://localhost:50895/tests/dromaeo/cssquery-jquery.html) 17:32:29 INFO - PROCESS | 1926 | RSS: Main: 378990592 17:32:29 INFO - PROCESS | 1926 | 17:35:31 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:50895/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50895/tests/dromaeo/cssquery-mootools.html) 17:35:32 INFO - PROCESS | 1926 | RSS: Main: 370569216 17:35:32 INFO - PROCESS | 1926 | 17:38:34 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:50895/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50895/tests/dromaeo/cssquery-prototype.html) 17:38:35 INFO - PROCESS | 1926 | RSS: Main: 370577408 17:38:35 INFO - PROCESS | 1926 | 17:41:38 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:50895/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50895/tests/dromaeo/cssquery-yui.html) 17:41:39 INFO - PROCESS | 1926 | RSS: Main: 332185600 17:41:39 INFO - PROCESS | 1926 | 17:44:42 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:50895/tests/dromaeo/cssquery-yui.html (next: http://localhost:50895/tests/dromaeo/cssquery-dojo.html) 17:44:42 INFO - PROCESS | 1926 | RSS: Main: 380149760 17:44:42 INFO - PROCESS | 1926 | 17:44:42 INFO - PROCESS | 1926 | __start_tp_report 17:44:42 INFO - PROCESS | 1926 | _x_x_mozilla_page_load 17:44:42 INFO - PROCESS | 1926 | _x_x_mozilla_page_load_details 17:44:42 INFO - PROCESS | 1926 | |i|pagename|runs| 17:44:42 INFO - PROCESS | 1926 | |0;dojo.html;3169.207698075481;3339.8831040913556;3343.047231539012;3359.9496007559883;3375.2574433624595;5613.410591887854;5623.578231632629;5714.028615135427;5735.139729040636;5748.741306641206;5679.318481782192;5715.457031581993;5807.970960145204;5810.18657387965;5817.272840894888;5169.508896654831;5176.55999240065;5241.764120614582;5248.317718696564;5339.786408543673;5316.787328506855;5351.411344752074;5366.91949620756;5379.515843574078;5408.810691625794;5152.665076770017;5222.764975576109;5225.320708307933;5267.051930652481;5275.419703832576;4762.28565715142;4870.975645121787;5169;5212.322398088262;5247.1866860636665;5172.793088276464;5204.245384419238;5204.479552044803;5253.553447956928;5305.708186049766;5779.2775903012125;5785.363610002896;5804.680742559158;5843.094320380348;5848.97075514624;5100.852308230656;6050;6205.596636218631;6230.59501132425;6302.55882088254;5891.941080589182;6333.9683301583655;6335.746570137189;6357.459615932651;6362.586431881913;5707.71461426927;5856.267966504187;5872.823815285506;5886.529077673776;5895.852603684943;6245.5628106033055;6309.211348581427;6315.273743519554;6320.55756097078;6332.8416789579705;6155.569110162246;6188.288346840171;6188.690565471708;6197.070439434031;6207.255129384413;6117.449429551273;6131.468419844996;6316.557840951089;6341.746330146742;6345.746170153142;5157.381114266312;6132.93684452123;6149.954507733696;6155.169052177988;6224.346443623445;6301.40136687014;6328.525360597973;6334.366563343721;6345.07996340528;6355.046742988588;5012.423571289349;5017.147084995559;5023.020511000393;5028.371453568304;5044.772985215674;4415.536368681306;4429.069895321954;4442.8667139985855;4484.327350897326;4541.409616749801;5734.827955161351;5737.77048918047;5738.311402631711;5742.626729262584;5797.01450753373;5289.576833853283;6306;6322.9051564226575;6363.204599425072;6367.076773867763;5572.4706152915405;5632.2678051853;5637.069883469182;5733.455321744427;5736.082226843685;5412.7293635318865;5440.265564148868;5442.863928401822;5449.700266485302;5451.700156491431;6277.937220627735;6290.119383286252;6331;6339.429451349401;6347.270063942706;6176.536759743127;6288.276848162337;6314.905276420765;6334.429901308904;6377.17096777416;6344.587601805868;6360.268569114611;6386.584871983492;6390.616563006234;6391.169148010729;6084.361142080018;6233.033879748465;6268.153799237045;6269.435750782452;6269.68651567429;6164.321924588202;6199.16311297969;6238.033104868752;6280.591761535486;6302.212223472066;5129.333186685707;6120.571559990758;6126.050462178369;6130.356312587114;6140.692965351804;5954.106883967266;6041.6375017499095;6091.0558863376245;6096.329403765493;6096.786612468542;5980.461758441761;6055.364186760326;6064.72681433104;6100.908486372797;6113.02191649334;6184.165137706352;6212.006079027334;6218.720157592828;6235.095911092775;6242.968785156045;5849.64902105875;5894.381089985662;5902.38025007368;6048.939510604838;6053.273607167168 17:44:42 INFO - PROCESS | 1926 | |1;ext.html;471.7358279363557;507.7055307921406;519.6051001239057;529.4440837121024;551.68278240012;31156.376872462537;32991.34017319655;41767.746967590945;42627.78686106565;42666.786666066706;44359.77820110903;44773.552264477345;44794.55205447945;44795.32807007898;44903;9828.312018158731;9828.459434731087;9828.852567211497;9846.261530385233;9858.507074646275;8763.912360876373;8973.32700047499;11913.583024594142;12063.215890967103;12103.818442723323;11940.7611847763;11994.280343179393;12010.219335743192;12026.218295810744;12026.338551379671;8868.556572171366;11934.820977685342;11962.641120766304;11964;11972.341521216329;11538;11806;11816.231944923553;12064.457099430547;12071.336076515789;41851.58148418538;42177.15645687069;42190.578094218974;42238.78880605608;42252.73241802751;2970.8366039867797;3083.4603944309656;3128.8435578221224;3668.4497325401403;4201.369794530814;24688;31822.522662159856;32151.51772723411;32725.672743272502;33669.831650841836;3335.5830521184853;3374.8818791342187;3410.2667926395698;3429.9314013719586;3453.9654603454146;518279;521472;521654;522665;523796;61926.380736192965;62233.066504002476;62313;62516.68741656263;62530.687346562976;65237.476252374385;65320.673396632716;65355.34644653588;65381.673091634235;65395.67302163459;16342.75485867713;16607.920485168666;16610.252538635792;16617.252223649724;16621.83378166203;11384;11627.883721162849;12844.422001009976;12923.353832308536;12931.612051638465;14846.851531484763;15691.058536487848;16242.431514896922;16993.065381403892;17135.05757183367;5908.645481271137;6388.8250517081315;6526.641034743135;6588.670566471657;6656.5340426169705;905.6920646980059;929.6467342409976;1036.1658864613908;1051.453244312953;1108.8780234174235;31123.533147002814;31430;31440.84279578633;31698.524522132186;32504.512432313535;1022.3303736052898;1105.867295924494;1123.292325834719;1140.0822338017808;1163.7672465506932;13361.465541378237;15721.842781572266;16142.75785863213;16225.756613650805;16566.254518546695;664984;669549;674246;677332.6133369401;678730;61417.38582614206;61423.38576614266;61462;61510.769784604054;61907.76184476375;1248.1138391741783;1376.181172202538;1399.5381524097024;1421.118906278073;1432.7635940069981;27066;32243;32318.676813231566;32409.35181296408;32602.85889993839;51358.48641513537;51856.48143518517;51892.96214075773;51938;52162.95674086421;490138;508246;509379;509551;510261;33614.66385336213;34646.13384665303;35108.473372899934;35374.29251415009;37168;45232.77383613061;47893;49132.50867491422;49300;49333.76665583389;39702;40499.785006449856;40832.591674082876;42026.57973420349;43361.3495797557;40246.79876600598;40956;41618.79190604028;42232.788836055624;44177.895552611444;39693.80153099216;39714.60285397225;40093;40431.59568404278;40662.79668601638;34899;35566;36457;36556.634433656385;36611.63388366082;26165.869170654787;26181;26374.20877373682;26394.73605263923;27468.759372187866 17:44:42 INFO - PROCESS | 1926 | |2;jquery.html;382.6403181009851;390.5645205595762;394.88745707473373;399.5485101834926;413.309772679625;19646.410607681773;20393;21214.469638259034;21216.363509094732;21725.782742172574;20884.791152088474;21066.052027658752;21256.4685882853;22082.33752987409;22109.447263818412;5516.558675305966;5925.940740592603;5946.108083787423;5948.583599148062;5978.55160862937;4143.7720925349095;4368.148211098837;5904.468597826195;6256.906146407785;6569.967150164266;6287.717052732638;6569.113169722098;6583.177102862142;6597.175353080865;6599.439047680953;6540.836479088013;6583.308752580958;6584.6707664616815;6598.7030583623855;6613.338666133396;5201.739913004335;5479.986202552541;5498.890022199574;5733.996550603628;5735.340435849889;13637;13891.722165556632;13963.511277105354;18829.34097306588;26740.59889101665;2105.4104850641693;2129.350548082834;2203.900824462887;2319.5940710375617;2334.4514039200776;10237.515560243797;10345.482725863676;10412.895871041268;10515.78968420635;14084.943629227746;1593.5617705131065;1714.7599336092958;1856.712209607513;1891.9810801892081;1919.683252263375;116812;117232.96602203895;121371;122218;122598.38700806529;9998.350107243006;10856.728581785519;11475.254108483088;11743.366811572547;11866.821997670042;14595.92702036483;14693.971422000357;14694.779578306334;14714.33785479656;14728.263586820616;7624.504407213513;8178.427510074357;8395.202455766692;8630.136986301319;8696.956515217384;6848.452123830081;8496.617652205665;8523.488590684456;8630.525321107274;8652;4546.386237857913;4572.588467037983;4605.148047611203;4671.135839869635;4692.906141877143;1542.6606146647719;1616.9029858208546;1737.9912252178217;1767.5139336682257;1914.9521261968562;881.8633111867671;916.331078312822;941.3363578677016;958.434523631061;1005.5123265216511;20616.38150855477;20620.381388558366;21529.67705484419;21764.45588860291;21907.452313691967;866;948.5778828421286;954.0078318548632;987.2052997337213;1018.8675472188568;8704.695335663222;11516;11551.76896462066;11554.342501224766;11591.420428978518;616752;645501;659903;670887;671826;132516.67483325236;135092.64907350997;136212;137936.31031844777;138924;1016.2885979814012;1037.2790910317408;1052.899974502421;1054.7468607534136;1056.3133962924007;14635.649360653482;19384.903075485097;19419;19444.708329375353;19610.8038919609;43698;56650.58373540529;57486.7125664369;57841.710791445774;58157.70921145537;83651.16348836434;83677;84100.57949710212;86122;86572;8209.71266005687;8288.50268983863;8299.917000830155;8333.166683331618;8497;5986.6707331097205;9325.580348884452;12321.137520373488;12607.369631518572;13254.072214944861;10690.890990542559;10904.400257985886;11335.319880807177;11545.422728863692;11561.248518846249;8862.556872156496;11392.08863290918;11424.885751142714;11663.125265604944;11695.941520292345;9759.024097590183;10792.514336854703;11247.212695111264;11548.307101573933;11936.940315298369;7452.664630091551;7846.725364612211;7892.250236227549;7913.248241417056;8062.193780621891;7803.180666029985;9421.670241541513;10085.848712269468;11661.53353865836;11662.591809286634 17:44:42 INFO - PROCESS | 1926 | |3;mootools.html;1320.590616908758;1531.82384025837;1718.3470281293107;1918.434061951724;1935.4193741877434;8580.69967551135;8824.867626985584;9034.819303613933;9034.960979487361;9044.412113212637;7920.128785833568;8161.2246836550585;8172.63223154958;8275.834483310331;8844.380893337453;2144.9249276275295;2262.264763951714;2340.3564019894493;2343.16817529777;2352.4589344450787;2358.1039205102124;2366.7396586375485;2385.8329916905823;2397.9760202398015;2399.2202534176377;1766.743822145788;2347.6947996760396;2358.1628521874745;2359.7640235976437;2362.8818559072065;2290.713660792401;2380.1669415704364;2384.9284521464297;2387.415083304584;2387.6299173628117;2387.570237357275;2396.724376696667;2413.5414271288573;2428.490017096412;2429.0769507587006;8759.956200219021;8824.073472285447;8863.290936725047;8864.645414183426;8869.2017718405;5277.577793776489;5441.238226648273;5443.237946687467;5454.209139674765;5454.7272636368025;3528.2237907660274;3620.475031120473;3682.355587772129;3690.1881586051027;3694.685951694109;1011.7622358745689;1066.7386490309846;1098.7143342730867;1115.9051480624157;1119.9552017919273;10052.798944021151;11947.98442132411;11953.983911367544;11963.983061439789;11982.221155624944;4075.2257071156387;4531.909361812811;4533.979854532704;4614.3309220163555;4713.34013238147;10227.94886025565;10242.078212960869;10275.7431064224;10279.331843430153;10286.537105830255;5358.785648574091;5429.457054294618;5430.972845135749;5431.076716958127;5434.157705555645;4734.4081989751285;4917.188663870493;4942.9011419771405;4953.455119936804;4955.72743499111;3849.3071247175776;3856.3444214483607;3902.453656488094;3924.9607503924594;3933.8623148189677;1070.8768491623407;1180.701228648497;1181.7341098252825;1186.8575770907546;1190.9523619055142;860.7030574451804;893.8659201119884;918.9494577798151;962.7593101724569;1013.716159475348;5356.946430535722;5658.490735833712;6046.818595442144;6068.180795592627;6108.786192483241;433.8481531463962;435.16448419035527;440.55723997383063;443.3814828314483;464.3174533435844;4049.271131196413;4063.695222858238;4096.09885825124;4163.791810409467;4166.479190101238;10426.530806113591;10755;10878.129749620011;10880;10983.560657573767;10699.89300106989;10767.89232107669;10809.297395669257;10826.837597436044;10830.404327761891;6527.51043671717;7446.367058799954;8206.466579672302;8305.086440491541;8306.958465207756;5882.470577647861;5989.131575921379;5991.550633702577;6117.510599152146;6252.3122456530655;10080.848787268344;10091.44497052669;10172.237082219011;10213.693589192337;10221.846672299773;9189.540522973692;10278.023587759151;10346.741331466774;10366.429846358522;10385.896141038493;6660.533762636763;6893.379595836399;7154.282972086766;7163.677634506355;7249.710011599477;8313.6674533018;8333.499990000619;8341.874871877048;8411.621477033426;8429.283510901641;7547.50941188821;7584.696612135674;7622.2758837910305;7638.350740187147;7732.994710687574;7446;7482.663280152297;7506.211847755907;7631;7633.198514156155;7078.2921707830865;7089.468289878383;7146.964265178641;7164.462665300019;7170.928290717235;5087.898242035212;5128.58971282304;5145.331106956071;5168.147255702767;5234.947650523446;5093.694378337312;5126.974365128151;5195.792168313225;5320.334958130234;5343.225232341211 17:44:42 INFO - PROCESS | 1926 | |4;prototype.html;301.3265351938417;306.4759261662557;310.5078450655711;313.4342511766262;316.773506942536;4752;5323.030478722868;5433.64681295716;5440.3743569489525;5444.591655625824;5348.839534813952;5397.865053373667;5421.1297102499675;5429.348478182614;5430.70131142787;3851.5185601799776;4024.396340548926;4032;4037.1925614876995;4048.4534587830562;3323.435016047266;3434.8454319555553;3457.135716070982;3863.130795571002;3902.20004898995;3660;3990.5410877748996;3995.8601448949294;4013.1371755072655;4021.77880216588;4026.5973402659793;4032.495938007749;4049.898752531181;4055.0398209307077;4067.877963661065;3085.861136248852;3099.333643266686;3246.2533617267923;3459.15250763562;3518.4546395308766;4442.955570444319;5166.819161329336;5167.612429067797;5185.248139019821;5257.474252574751;3846.730429046876;4583.747893865836;4626.58360747531;4636.3045543168455;4654.976725116387;5312.990531798946;5578.828446026339;5587.653185662031;5604.1593760935775;5623.381428042912;3852.9807350963065;3887.658757728579;3887.922241555153;3888.941665874986;3929.8231579578987;5045.41977672565;5197.998770473358;5324.520793128637;5414.862878795458;5881.441263080001;3929.206368230754;3929.5677475477673;3939.3697008478507;3973.8211780469946;3981.2435637229414;3593.8140413663427;3850.8844734658005;4034.3948407738485;4042.7253924320808;4044.189288444503;4276.379924910871;4624.445066591963;4640.559146881041;4658.068386322749;4693.202155633482;5151.330327057459;5635.774569017193;5647.6893770843;5658.679941224078;5663.377028526858;1205.3551350027692;1295.4688577683105;1326.3103186343048;1351.4121357209644;1354.1604205392523;787.5353541410595;790.7150879820292;825.3479750996765;834.5535138700766;837.4556538250187;3655.2689462107683;4113.547509773923;4417.305443679353;4446.777661116931;4513.458384993822;4171.808566446671;4571.514257866207;4577.679562430598;4807.78364973577;4962.7022378656675;3774.509313789245;4319.870403887889;4399.986009583446;4445.2887537993765;4482.843100491467;2308.7691230877113;2396.508715713275;2432.5013372258645;2447.865367404774;2464.6056630938965;4671.738630569727;4777.593904518086;4977.427595826526;4999.850004499798;5069.746512674352;4665.696729712626;4847.248676455155;4948.490710333383;4956.802862153891;5013.97213571217;4339.7396156230725;4553.954460455486;4744.952550474451;5055.295416951986;5064.949350506447;4352.978235108804;4512.345709872116;4826.855194344175;4855.684380515255;4920.72935988524;4387.727559007852;4436.866893993185;4511.751853648081;4867.561919427269;4868.659193856396;4795.064962332452;5041.6470847040355;5473.917891231555;5663.2071509989455;5947.702614869153;3793.734438589272;3794.3684215787475;3800.125971026624;3823.3309170894427;3836.5396152461885;4836.032793441396;5022;5651.971740141437;5756.21557317233;5765.4811067004175;4940.481249468898;5243.7152897540345;5581.7209139543675;5655.519280861173;5699.914501282401;5194.532492075731;5418.59116629687;5574.247476590608;5674.319081710221;5701.857453563528;4433.024734558392;5446.645968012067;5581.972090139523;5602.775888964396;5604.56201552326;5645.209670646195;5646.520045796154;5658.717064146694;5677.432256774289;5688.374278829243;4873.585745211697;5493.258410114743;5594.992901277809;5605.831825045255;5617.747201375866 17:44:42 INFO - PROCESS | 1926 | |5;yui.html;606.5797468102272;753.8718417868962;767.7332401537466;836.6444261188993;844.9281811046059;14341.713165736679;14892.702145957075;15718.685626287468;16777.748333774987;16809.411670591548;13797.310134493286;13911.582652520441;13923.094998825069;13929.651758706037;15879.285432155551;889.6530353162289;894.4990805149138;907.4600612635471;909.417972497602;910.1626503616689;780.8321210939647;891.4383938118976;904.1049361132465;906.6645341223757;907.1472815553392;742.6695120671303;771.155584634824;774.7753151586062;950.9334346595741;952.9761755956122;769.9037620297463;770.9614519274041;947.346331031586;991.1624677147798;992.692265397729;709.9148102227714;715.248988562013;716.8422946951714;718.0700992215094;720.4200618502103;15808.051516909023;15812.762808557765;15851.128187949773;15855.36578536857;15870.365185392571;12565.685857853627;14740.484083057148;15016;15021.924890375478;15098.773518397233;9965.75085622858;11837.881621183764;11947.880521194764;11972.940135299355;12389;3376.139084533428;3415.334009868077;3468.549088618489;3509.5788505379264;3566.0728210665156;99433.5028324861;100073.4996325021;100176.49911750395;100742.48886266712;103984;10342.431166285776;11732.237404568676;11929.58246461387;11987.160898737005;12285.69285767863;24583.262502124966;24610.138645147334;24647.630285545732;24654.506909862062;24661;8177.182281771775;8621.69824056155;8746.037935827055;8749.518776467228;8771.561421928878;4400.185965596375;4606.262997920317;4618.30725391194;4703.694259873166;4768.904621907543;3555.5377800885717;3734.7012239021356;3741.7380783345448;3745.7190710696805;3750.9437358439645;638.9936100638934;647.0262255305735;647.7150053976235;648.6237981970446;650.0639079725181;551.8068675963461;582.3594046548762;598.7013244901561;628.3622123544606;630.3035146163515;8704.956475217583;9011.324150688724;9060.003399626035;9121.17909388145;9245.815083698288;552.6269767906728;553.4935533986438;587.2512546503157;591.9822405327847;595.3094410483905;4428.22506061438;4437.179121862466;4445.888852778642;4464.241079016575;4470.444948037805;94040;151003;154950.65234227624;158697.7338014686;159073.38995505893;81498.59250703709;96458;101533;123537.0043090618;130228;104869.47565262124;106721;108133.45933270283;111042;112708.43645781718;8540.27407670355;8542.273906718;8551.48081149349;8664.653413863374;8777.824443510966;65394;65751.01373479498;66394.67210655856;66635;68166.318336816;83314;97320;97693;98679.50660246653;99760.50119749646;11394.829077564009;11570.363630000431;11770.823437648613;11841.644750657493;11962.461689223832;12064.75870482568;12082.879171208175;12099.637010889688;12220.205686630341;12674.112812103065;11564.666566910933;11636;11663.475143618727;11742.119341049285;12078.879211207775;9570.712878613653;11865.762684746083;11882.643520694393;11884.940575297414;12044.879551204376;11516.366599837089;11539.134564907497;11604.593839215588;11788.70528236801;11942.522299108286;9732.80534389294;9749.658761943298;9796.951015244878;9919.305648604817;10135.493225338556;9564.033648643965;10141.797164056825;11129.849912182479;11552.364619945647;11670.591529296435 17:44:42 INFO - PROCESS | 1926 | __end_tp_report 17:44:42 INFO - PROCESS | 1926 | __start_cc_report 17:44:42 INFO - PROCESS | 1926 | _x_x_mozilla_cycle_collect,1228 17:44:42 INFO - PROCESS | 1926 | __end_cc_report 17:44:42 INFO - PROCESS | 1926 | __startTimestamp1468025082752__endTimestamp 17:44:42 INFO - PROCESS | 1926 | 17:44:42 INFO - PROCESS | 1926 | ------- Summary: start ------- 17:44:42 INFO - PROCESS | 1926 | Number of tests: 6 17:44:42 INFO - PROCESS | 1926 | 17:44:42 INFO - PROCESS | 1926 | [#0] dojo.html Cycles:165 Average:5755.98 Median:5902.38 stddev:654.58 (11.1%) stddev-sans-first:624.34 17:44:42 INFO - PROCESS | 1926 | Values: 3169.2 3339.9 3343.0 3359.9 3375.3 5613.4 5623.6 5714.0 5735.1 5748.7 5679.3 5715.5 5808.0 5810.2 5817.3 5169.5 5176.6 5241.8 5248.3 5339.8 5316.8 5351.4 5366.9 5379.5 5408.8 5152.7 5222.8 5225.3 5267.1 5275.4 4762.3 4871.0 5169.0 5212.3 5247.2 5172.8 5204.2 5204.5 5253.6 5305.7 5779.3 5785.4 5804.7 5843.1 5849.0 5100.9 6050.0 6205.6 6230.6 6302.6 5891.9 6334.0 6335.7 6357.5 6362.6 5707.7 5856.3 5872.8 5886.5 5895.9 6245.6 6309.2 6315.3 6320.6 6332.8 6155.6 6188.3 6188.7 6197.1 6207.3 6117.4 6131.5 6316.6 6341.7 6345.7 5157.4 6132.9 6150.0 6155.2 6224.3 6301.4 6328.5 6334.4 6345.1 6355.0 5012.4 5017.1 5023.0 5028.4 5044.8 4415.5 4429.1 4442.9 4484.3 4541.4 5734.8 5737.8 5738.3 5742.6 5797.0 5289.6 6306.0 6322.9 6363.2 6367.1 5572.5 5632.3 5637.1 5733.5 5736.1 5412.7 5440.3 5442.9 5449.7 5451.7 6277.9 6290.1 6331.0 6339.4 6347.3 6176.5 6288.3 6314.9 6334.4 6377.2 6344.6 6360.3 6386.6 6390.6 6391.2 6084.4 6233.0 6268.2 6269.4 6269.7 6164.3 6199.2 6238.0 6280.6 6302.2 5129.3 6120.6 6126.1 6130.4 6140.7 5954.1 6041.6 6091.1 6096.3 6096.8 5980.5 6055.4 6064.7 6100.9 6113.0 6184.2 6212.0 6218.7 6235.1 6243.0 5849.6 5894.4 5902.4 6048.9 6053.3 17:44:42 INFO - PROCESS | 1926 | 17:44:42 INFO - PROCESS | 1926 | [#1] ext.html Cycles:180 Average:71195.60 Median:38430.90 stddev:152557.16 (397.0%) stddev-sans-first:152892.71 17:44:42 INFO - PROCESS | 1926 | Values: 471.7 507.7 519.6 529.4 551.7 31156.4 32991.3 41767.7 42627.8 42666.8 44359.8 44773.6 44794.6 44795.3 44903.0 9828.3 9828.5 9828.9 9846.3 9858.5 8763.9 8973.3 11913.6 12063.2 12103.8 11940.8 11994.3 12010.2 12026.2 12026.3 8868.6 11934.8 11962.6 11964.0 11972.3 11538.0 11806.0 11816.2 12064.5 12071.3 41851.6 42177.2 42190.6 42238.8 42252.7 2970.8 3083.5 3128.8 3668.4 4201.4 24688.0 31822.5 32151.5 32725.7 33669.8 3335.6 3374.9 3410.3 3429.9 3454.0 518279.0 521472.0 521654.0 522665.0 523796.0 61926.4 62233.1 62313.0 62516.7 62530.7 65237.5 65320.7 65355.3 65381.7 65395.7 16342.8 16607.9 16610.3 16617.3 16621.8 11384.0 11627.9 12844.4 12923.4 12931.6 14846.9 15691.1 16242.4 16993.1 17135.1 5908.6 6388.8 6526.6 6588.7 6656.5 905.7 929.6 1036.2 1051.5 1108.9 31123.5 31430.0 31440.8 31698.5 32504.5 1022.3 1105.9 1123.3 1140.1 1163.8 13361.5 15721.8 16142.8 16225.8 16566.3 664984.0 669549.0 674246.0 677332.6 678730.0 61417.4 61423.4 61462.0 61510.8 61907.8 1248.1 1376.2 1399.5 1421.1 1432.8 27066.0 32243.0 32318.7 32409.4 32602.9 51358.5 51856.5 51893.0 51938.0 52163.0 490138.0 508246.0 509379.0 509551.0 510261.0 33614.7 34646.1 35108.5 35374.3 37168.0 45232.8 47893.0 49132.5 49300.0 49333.8 39702.0 40499.8 40832.6 42026.6 43361.3 40246.8 40956.0 41618.8 42232.8 44177.9 39693.8 39714.6 40093.0 40431.6 40662.8 34899.0 35566.0 36457.0 36556.6 36611.6 26165.9 26181.0 26374.2 26394.7 27468.8 17:44:42 INFO - PROCESS | 1926 | 17:44:42 INFO - PROCESS | 1926 | [#2] jquery.html Cycles:180 Average:36851.98 Median:12214.52 stddev:108928.48 (891.8%) stddev-sans-first:109199.63 17:44:42 INFO - PROCESS | 1926 | Values: 382.6 390.6 394.9 399.5 413.3 19646.4 20393.0 21214.5 21216.4 21725.8 20884.8 21066.1 21256.5 22082.3 22109.4 5516.6 5925.9 5946.1 5948.6 5978.6 4143.8 4368.1 5904.5 6256.9 6570.0 6287.7 6569.1 6583.2 6597.2 6599.4 6540.8 6583.3 6584.7 6598.7 6613.3 5201.7 5480.0 5498.9 5734.0 5735.3 13637.0 13891.7 13963.5 18829.3 26740.6 2105.4 2129.4 2203.9 2319.6 2334.5 10237.5 10345.5 10412.9 10515.8 14084.9 1593.6 1714.8 1856.7 1892.0 1919.7 116812.0 117233.0 121371.0 122218.0 122598.4 9998.4 10856.7 11475.3 11743.4 11866.8 14595.9 14694.0 14694.8 14714.3 14728.3 7624.5 8178.4 8395.2 8630.1 8697.0 6848.5 8496.6 8523.5 8630.5 8652.0 4546.4 4572.6 4605.1 4671.1 4692.9 1542.7 1616.9 1738.0 1767.5 1915.0 881.9 916.3 941.3 958.4 1005.5 20616.4 20620.4 21529.7 21764.5 21907.5 866.0 948.6 954.0 987.2 1018.9 8704.7 11516.0 11551.8 11554.3 11591.4 616752.0 645501.0 659903.0 670887.0 671826.0 132516.7 135092.6 136212.0 137936.3 138924.0 1016.3 1037.3 1052.9 1054.7 1056.3 14635.6 19384.9 19419.0 19444.7 19610.8 43698.0 56650.6 57486.7 57841.7 58157.7 83651.2 83677.0 84100.6 86122.0 86572.0 8209.7 8288.5 8299.9 8333.2 8497.0 5986.7 9325.6 12321.1 12607.4 13254.1 10690.9 10904.4 11335.3 11545.4 11561.2 8862.6 11392.1 11424.9 11663.1 11695.9 9759.0 10792.5 11247.2 11548.3 11936.9 7452.7 7846.7 7892.3 7913.2 8062.2 7803.2 9421.7 10085.8 11661.5 11662.6 17:44:42 INFO - PROCESS | 1926 | 17:44:42 INFO - PROCESS | 1926 | [#3] mootools.html Cycles:180 Average:5729.59 Median:4532.94 stddev:3237.13 (71.4%) stddev-sans-first:3229.25 17:44:42 INFO - PROCESS | 1926 | Values: 1320.6 1531.8 1718.3 1918.4 1935.4 8580.7 8824.9 9034.8 9035.0 9044.4 7920.1 8161.2 8172.6 8275.8 8844.4 2144.9 2262.3 2340.4 2343.2 2352.5 2358.1 2366.7 2385.8 2398.0 2399.2 1766.7 2347.7 2358.2 2359.8 2362.9 2290.7 2380.2 2384.9 2387.4 2387.6 2387.6 2396.7 2413.5 2428.5 2429.1 8760.0 8824.1 8863.3 8864.6 8869.2 5277.6 5441.2 5443.2 5454.2 5454.7 3528.2 3620.5 3682.4 3690.2 3694.7 1011.8 1066.7 1098.7 1115.9 1120.0 10052.8 11948.0 11954.0 11964.0 11982.2 4075.2 4531.9 4534.0 4614.3 4713.3 10227.9 10242.1 10275.7 10279.3 10286.5 5358.8 5429.5 5431.0 5431.1 5434.2 4734.4 4917.2 4942.9 4953.5 4955.7 3849.3 3856.3 3902.5 3925.0 3933.9 1070.9 1180.7 1181.7 1186.9 1191.0 860.7 893.9 918.9 962.8 1013.7 5356.9 5658.5 6046.8 6068.2 6108.8 433.8 435.2 440.6 443.4 464.3 4049.3 4063.7 4096.1 4163.8 4166.5 10426.5 10755.0 10878.1 10880.0 10983.6 10699.9 10767.9 10809.3 10826.8 10830.4 6527.5 7446.4 8206.5 8305.1 8307.0 5882.5 5989.1 5991.6 6117.5 6252.3 10080.8 10091.4 10172.2 10213.7 10221.8 9189.5 10278.0 10346.7 10366.4 10385.9 6660.5 6893.4 7154.3 7163.7 7249.7 8313.7 8333.5 8341.9 8411.6 8429.3 7547.5 7584.7 7622.3 7638.4 7733.0 7446.0 7482.7 7506.2 7631.0 7633.2 7078.3 7089.5 7147.0 7164.5 7170.9 5087.9 5128.6 5145.3 5168.1 5234.9 5093.7 5127.0 5195.8 5320.3 5343.2 17:44:42 INFO - PROCESS | 1926 | 17:44:42 INFO - PROCESS | 1926 | [#4] prototype.html Cycles:180 Average:4323.48 Median:4661.88 stddev:1321.93 (28.4%) stddev-sans-first:1290.70 17:44:42 INFO - PROCESS | 1926 | Values: 301.3 306.5 310.5 313.4 316.8 4752.0 5323.0 5433.6 5440.4 5444.6 5348.8 5397.9 5421.1 5429.3 5430.7 3851.5 4024.4 4032.0 4037.2 4048.5 3323.4 3434.8 3457.1 3863.1 3902.2 3660.0 3990.5 3995.9 4013.1 4021.8 4026.6 4032.5 4049.9 4055.0 4067.9 3085.9 3099.3 3246.3 3459.2 3518.5 4443.0 5166.8 5167.6 5185.2 5257.5 3846.7 4583.7 4626.6 4636.3 4655.0 5313.0 5578.8 5587.7 5604.2 5623.4 3853.0 3887.7 3887.9 3888.9 3929.8 5045.4 5198.0 5324.5 5414.9 5881.4 3929.2 3929.6 3939.4 3973.8 3981.2 3593.8 3850.9 4034.4 4042.7 4044.2 4276.4 4624.4 4640.6 4658.1 4693.2 5151.3 5635.8 5647.7 5658.7 5663.4 1205.4 1295.5 1326.3 1351.4 1354.2 787.5 790.7 825.3 834.6 837.5 3655.3 4113.5 4417.3 4446.8 4513.5 4171.8 4571.5 4577.7 4807.8 4962.7 3774.5 4319.9 4400.0 4445.3 4482.8 2308.8 2396.5 2432.5 2447.9 2464.6 4671.7 4777.6 4977.4 4999.9 5069.7 4665.7 4847.2 4948.5 4956.8 5014.0 4339.7 4554.0 4745.0 5055.3 5064.9 4353.0 4512.3 4826.9 4855.7 4920.7 4387.7 4436.9 4511.8 4867.6 4868.7 4795.1 5041.6 5473.9 5663.2 5947.7 3793.7 3794.4 3800.1 3823.3 3836.5 4836.0 5022.0 5652.0 5756.2 5765.5 4940.5 5243.7 5581.7 5655.5 5699.9 5194.5 5418.6 5574.2 5674.3 5701.9 4433.0 5446.6 5582.0 5602.8 5604.6 5645.2 5646.5 5658.7 5677.4 5688.4 4873.6 5493.3 5595.0 5605.8 5617.7 17:44:42 INFO - PROCESS | 1926 | 17:44:42 INFO - PROCESS | 1926 | [#5] yui.html Cycles:180 Average:23850.75 Median:4432.70 stddev:37743.22 (851.5%) stddev-sans-first:37808.75 17:44:42 INFO - PROCESS | 1926 | Values: 606.6 753.9 767.7 836.6 844.9 14341.7 14892.7 15718.7 16777.7 16809.4 13797.3 13911.6 13923.1 13929.7 15879.3 889.7 894.5 907.5 909.4 910.2 780.8 891.4 904.1 906.7 907.1 742.7 771.2 774.8 950.9 953.0 769.9 771.0 947.3 991.2 992.7 709.9 715.2 716.8 718.1 720.4 15808.1 15812.8 15851.1 15855.4 15870.4 12565.7 14740.5 15016.0 15021.9 15098.8 9965.8 11837.9 11947.9 11972.9 12389.0 3376.1 3415.3 3468.5 3509.6 3566.1 99433.5 100073.5 100176.5 100742.5 103984.0 10342.4 11732.2 11929.6 11987.2 12285.7 24583.3 24610.1 24647.6 24654.5 24661.0 8177.2 8621.7 8746.0 8749.5 8771.6 4400.2 4606.3 4618.3 4703.7 4768.9 3555.5 3734.7 3741.7 3745.7 3750.9 639.0 647.0 647.7 648.6 650.1 551.8 582.4 598.7 628.4 630.3 8705.0 9011.3 9060.0 9121.2 9245.8 552.6 553.5 587.3 592.0 595.3 4428.2 4437.2 4445.9 4464.2 4470.4 94040.0 151003.0 154950.7 158697.7 159073.4 81498.6 96458.0 101533.0 123537.0 130228.0 104869.5 106721.0 108133.5 111042.0 112708.4 8540.3 8542.3 8551.5 8664.7 8777.8 65394.0 65751.0 66394.7 66635.0 68166.3 83314.0 97320.0 97693.0 98679.5 99760.5 11394.8 11570.4 11770.8 11841.6 11962.5 12064.8 12082.9 12099.6 12220.2 12674.1 11564.7 11636.0 11663.5 11742.1 12078.9 9570.7 11865.8 11882.6 11884.9 12044.9 11516.4 11539.1 11604.6 11788.7 11942.5 9732.8 9749.7 9797.0 9919.3 10135.5 9564.0 10141.8 11129.8 11552.4 11670.6 17:44:42 INFO - PROCESS | 1926 | -------- Summary: end -------- 17:44:42 INFO - PROCESS | 1926 | 17:44:43 INFO - TEST-INFO | 1926: exit 0 17:44:43 INFO - TEST-OK | dromaeo_css | took 1116531ms 17:44:43 INFO - TEST-START | kraken 17:44:43 INFO - operating with platform_type : mac_ 17:44:43 INFO - Initialising browser for kraken test... 17:44:43 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmpCCyXSm/profile http://localhost:50895/getInfo.html) 17:44:45 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 17:44:45 INFO - PROCESS | 1932 | colorDepth:24 17:44:45 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 17:44:45 INFO - PROCESS | 1932 | __metrics 17:44:45 INFO - PROCESS | 1932 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:44:45 INFO - PROCESS | 1932 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:44:46 INFO - TEST-INFO | 1932: exit 0 17:44:46 INFO - Browser initialized. 17:44:46 INFO - Running cycle 1/1 for kraken test... 17:44:46 INFO - TEST-INFO | started process 1933 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmpCCyXSm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:44:58 INFO - PROCESS | 1933 | RSS: Main: 243585024 17:44:58 INFO - PROCESS | 1933 | 17:45:00 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50895/tests/kraken/driver.html?testName=audio-beat-detection) 17:45:01 INFO - PROCESS | 1933 | RSS: Main: 293904384 17:45:01 INFO - PROCESS | 1933 | 17:45:04 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50895/tests/kraken/driver.html?testName=audio-dft) 17:45:04 INFO - PROCESS | 1933 | RSS: Main: 304111616 17:45:04 INFO - PROCESS | 1933 | 17:45:08 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50895/tests/kraken/driver.html?testName=audio-fft) 17:45:09 INFO - PROCESS | 1933 | RSS: Main: 388399104 17:45:09 INFO - PROCESS | 1933 | 17:45:11 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50895/tests/kraken/driver.html?testName=audio-oscillator) 17:45:11 INFO - PROCESS | 1933 | RSS: Main: 289558528 17:45:11 INFO - PROCESS | 1933 | 17:45:13 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50895/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:45:14 INFO - PROCESS | 1933 | RSS: Main: 294105088 17:45:14 INFO - PROCESS | 1933 | 17:45:17 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50895/tests/kraken/driver.html?testName=imaging-darkroom) 17:45:17 INFO - PROCESS | 1933 | RSS: Main: 331210752 17:45:17 INFO - PROCESS | 1933 | 17:45:20 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50895/tests/kraken/driver.html?testName=imaging-desaturate) 17:45:21 INFO - PROCESS | 1933 | RSS: Main: 333602816 17:45:21 INFO - PROCESS | 1933 | 17:45:24 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50895/tests/kraken/driver.html?testName=json-parse-financial) 17:45:24 INFO - PROCESS | 1933 | RSS: Main: 330338304 17:45:24 INFO - PROCESS | 1933 | 17:45:26 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50895/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:45:26 INFO - PROCESS | 1933 | RSS: Main: 311566336 17:45:26 INFO - PROCESS | 1933 | 17:45:28 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50895/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:45:29 INFO - PROCESS | 1933 | RSS: Main: 318087168 17:45:29 INFO - PROCESS | 1933 | 17:45:31 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50895/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:45:32 INFO - PROCESS | 1933 | RSS: Main: 315834368 17:45:32 INFO - PROCESS | 1933 | 17:45:35 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50895/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:45:35 INFO - PROCESS | 1933 | RSS: Main: 324919296 17:45:35 INFO - PROCESS | 1933 | 17:45:38 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50895/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:45:39 INFO - PROCESS | 1933 | RSS: Main: 327319552 17:45:39 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50895/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50895/tests/kraken/driver.html?testName=ai-astar) 17:45:41 INFO - PROCESS | 1933 | RSS: Main: 370741248 17:45:41 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | __start_tp_report 17:45:41 INFO - PROCESS | 1933 | _x_x_mozilla_page_load 17:45:41 INFO - PROCESS | 1933 | _x_x_mozilla_page_load_details 17:45:41 INFO - PROCESS | 1933 | |i|pagename|runs| 17:45:41 INFO - PROCESS | 1933 | |0;ai-astar;102;112;113;113;113;114;111;113;112;114 17:45:41 INFO - PROCESS | 1933 | |1;audio-beat-detection;148;113;106;156;107;110;128;105;138;107 17:45:41 INFO - PROCESS | 1933 | |2;audio-dft;184;185;186;181;181;184;188;220;185;184 17:45:41 INFO - PROCESS | 1933 | |3;audio-fft;77;77;60;131;80;80;62;59;97;47 17:45:41 INFO - PROCESS | 1933 | |4;audio-oscillator;84;82;102;101;100;98;97;69;67;100 17:45:41 INFO - PROCESS | 1933 | |5;imaging-gaussian-blur;87;85;88;85;88;84;88;85;87;85 17:45:41 INFO - PROCESS | 1933 | |6;imaging-darkroom;93;93;94;95;94;94;94;94;95;93 17:45:41 INFO - PROCESS | 1933 | |7;imaging-desaturate;85;84;84;86;84;86;84;86;84;86 17:45:41 INFO - PROCESS | 1933 | |8;json-parse-financial;54;53;54;53;54;54;53;53;56;53 17:45:41 INFO - PROCESS | 1933 | |9;json-stringify-tinderbox;64;77;66;75;72;70;68;68;74;100 17:45:41 INFO - PROCESS | 1933 | |10;stanford-crypto-aes;83;82;83;84;80;85;102;100;85;83 17:45:41 INFO - PROCESS | 1933 | |11;stanford-crypto-ccm;139;143;141;125;118;156;124;123;231;142 17:45:41 INFO - PROCESS | 1933 | |12;stanford-crypto-pbkdf2;163;162;160;164;163;160;218;239;164;161 17:45:41 INFO - PROCESS | 1933 | |13;stanford-crypto-sha256-iterative;66;65;65;65;77;87;80;85;83;66 17:45:41 INFO - PROCESS | 1933 | __end_tp_report 17:45:41 INFO - PROCESS | 1933 | __start_cc_report 17:45:41 INFO - PROCESS | 1933 | _x_x_mozilla_cycle_collect,2075 17:45:41 INFO - PROCESS | 1933 | __end_cc_report 17:45:41 INFO - PROCESS | 1933 | __startTimestamp1468025141632__endTimestamp 17:45:41 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | ------- Summary: start ------- 17:45:41 INFO - PROCESS | 1933 | Number of tests: 14 17:45:41 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:3.53 (3.1%) stddev-sans-first:0.97 17:45:41 INFO - PROCESS | 1933 | Values: 102.0 112.0 113.0 113.0 113.0 114.0 111.0 113.0 112.0 114.0 17:45:41 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:120.50 stddev:19.27 (16.0%) stddev-sans-first:17.96 17:45:41 INFO - PROCESS | 1933 | Values: 148.0 113.0 106.0 156.0 107.0 110.0 128.0 105.0 138.0 107.0 17:45:41 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | [#2] audio-dft Cycles:10 Average:187.80 Median:185.00 stddev:11.51 (6.2%) stddev-sans-first:12.12 17:45:41 INFO - PROCESS | 1933 | Values: 184.0 185.0 186.0 181.0 181.0 184.0 188.0 220.0 185.0 184.0 17:45:41 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | [#3] audio-fft Cycles:10 Average:77.00 Median:77.00 stddev:23.74 (30.8%) stddev-sans-first:25.18 17:45:41 INFO - PROCESS | 1933 | Values: 77.0 77.0 60.0 131.0 80.0 80.0 62.0 59.0 97.0 47.0 17:45:41 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | [#4] audio-oscillator Cycles:10 Average:90.00 Median:75.50 stddev:13.53 (17.9%) stddev-sans-first:14.18 17:45:41 INFO - PROCESS | 1933 | Values: 84.0 82.0 102.0 101.0 100.0 98.0 97.0 69.0 67.0 100.0 17:45:41 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.00 stddev:1.55 (1.8%) stddev-sans-first:1.62 17:45:41 INFO - PROCESS | 1933 | Values: 87.0 85.0 88.0 85.0 88.0 84.0 88.0 85.0 87.0 85.0 17:45:41 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 17:45:41 INFO - PROCESS | 1933 | Values: 93.0 93.0 94.0 95.0 94.0 94.0 94.0 94.0 95.0 93.0 17:45:41 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.50 stddev:0.99 (1.2%) stddev-sans-first:1.05 17:45:41 INFO - PROCESS | 1933 | Values: 85.0 84.0 84.0 86.0 84.0 86.0 84.0 86.0 84.0 86.0 17:45:41 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:0.95 (1.8%) stddev-sans-first:1.00 17:45:41 INFO - PROCESS | 1933 | Values: 54.0 53.0 54.0 53.0 54.0 54.0 53.0 53.0 56.0 53.0 17:45:41 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | [#9] json-stringify-tinderbox Cycles:10 Average:73.40 Median:71.00 stddev:10.21 (14.4%) stddev-sans-first:10.25 17:45:41 INFO - PROCESS | 1933 | Values: 64.0 77.0 66.0 75.0 72.0 70.0 68.0 68.0 74.0 100.0 17:45:41 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:83.00 stddev:7.69 (9.3%) stddev-sans-first:8.04 17:45:41 INFO - PROCESS | 1933 | Values: 83.0 82.0 83.0 84.0 80.0 85.0 102.0 100.0 85.0 83.0 17:45:41 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | [#11] stanford-crypto-ccm Cycles:10 Average:144.20 Median:141.50 stddev:32.71 (23.1%) stddev-sans-first:34.64 17:45:41 INFO - PROCESS | 1933 | Values: 139.0 143.0 141.0 125.0 118.0 156.0 124.0 123.0 231.0 142.0 17:45:41 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.40 Median:163.50 stddev:28.46 (17.4%) stddev-sans-first:29.83 17:45:41 INFO - PROCESS | 1933 | Values: 163.0 162.0 160.0 164.0 163.0 160.0 218.0 239.0 164.0 161.0 17:45:41 INFO - PROCESS | 1933 | 17:45:41 INFO - PROCESS | 1933 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.90 Median:78.50 stddev:9.35 (11.9%) stddev-sans-first:9.47 17:45:41 INFO - PROCESS | 1933 | Values: 66.0 65.0 65.0 65.0 77.0 87.0 80.0 85.0 83.0 66.0 17:45:41 INFO - PROCESS | 1933 | -------- Summary: end -------- 17:45:41 INFO - PROCESS | 1933 | 17:45:42 INFO - TEST-INFO | 1933: exit 0 17:45:42 INFO - TEST-OK | kraken | took 59067ms 17:45:42 INFO - SUITE-END | took 1175s 17:45:42 INFO - Completed test suite (00:19:35) 17:45:42 INFO - javascript benchmark 17:45:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3169.207698075481, 3339.8831040913556, 3343.047231539012, 3359.9496007559883, 3375.2574433624595, 5613.410591887854, 5623.578231632629, 5714.028615135427, 5735.139729040636, 5748.741306641206, 5679.318481782192, 5715.457031581993, 5807.970960145204, 5810.18657387965, 5817.272840894888, 5169.508896654831, 5176.55999240065, 5241.764120614582, 5248.317718696564, 5339.786408543673, 5316.787328506855, 5351.411344752074, 5366.91949620756, 5379.515843574078, 5408.810691625794, 5152.665076770017, 5222.764975576109, 5225.320708307933, 5267.051930652481, 5275.419703832576, 4762.28565715142, 4870.975645121787, 5169.0, 5212.322398088262, 5247.1866860636665, 5172.793088276464, 5204.245384419238, 5204.479552044803, 5253.553447956928, 5305.708186049766, 5779.2775903012125, 5785.363610002896, 5804.680742559158, 5843.094320380348, 5848.97075514624, 5100.852308230656, 6050.0, 6205.596636218631, 6230.59501132425, 6302.55882088254, 5891.941080589182, 6333.9683301583655, 6335.746570137189, 6357.459615932651, 6362.586431881913, 5707.71461426927, 5856.267966504187, 5872.823815285506, 5886.529077673776, 5895.852603684943, 6245.5628106033055, 6309.211348581427, 6315.273743519554, 6320.55756097078, 6332.8416789579705, 6155.569110162246, 6188.288346840171, 6188.690565471708, 6197.070439434031, 6207.255129384413, 6117.449429551273, 6131.468419844996, 6316.557840951089, 6341.746330146742, 6345.746170153142, 5157.381114266312, 6132.93684452123, 6149.954507733696, 6155.169052177988, 6224.346443623445, 6301.40136687014, 6328.525360597973, 6334.366563343721, 6345.07996340528, 6355.046742988588, 5012.423571289349, 5017.147084995559, 5023.020511000393, 5028.371453568304, 5044.772985215674, 4415.536368681306, 4429.069895321954, 4442.8667139985855, 4484.327350897326, 4541.409616749801, 5734.827955161351, 5737.77048918047, 5738.311402631711, 5742.626729262584, 5797.01450753373, 5289.576833853283, 6306.0, 6322.9051564226575, 6363.204599425072, 6367.076773867763, 5572.4706152915405, 5632.2678051853, 5637.069883469182, 5733.455321744427, 5736.082226843685, 5412.7293635318865, 5440.265564148868, 5442.863928401822, 5449.700266485302, 5451.700156491431, 6277.937220627735, 6290.119383286252, 6331.0, 6339.429451349401, 6347.270063942706, 6176.536759743127, 6288.276848162337, 6314.905276420765, 6334.429901308904, 6377.17096777416, 6344.587601805868, 6360.268569114611, 6386.584871983492, 6390.616563006234, 6391.169148010729, 6084.361142080018, 6233.033879748465, 6268.153799237045, 6269.435750782452, 6269.68651567429, 6164.321924588202, 6199.16311297969, 6238.033104868752, 6280.591761535486, 6302.212223472066, 5129.333186685707, 6120.571559990758, 6126.050462178369, 6130.356312587114, 6140.692965351804, 5954.106883967266, 6041.6375017499095, 6091.0558863376245, 6096.329403765493, 6096.786612468542, 5980.461758441761, 6055.364186760326, 6064.72681433104, 6100.908486372797, 6113.02191649334, 6184.165137706352, 6212.006079027334, 6218.720157592828, 6235.095911092775, 6242.968785156045, 5849.64902105875, 5894.381089985662, 5902.38025007368, 6048.939510604838, 6053.273607167168], "value": 5714.0722903466385, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.7358279363557, 507.7055307921406, 519.6051001239057, 529.4440837121024, 551.68278240012, 31156.376872462537, 32991.34017319655, 41767.746967590945, 42627.78686106565, 42666.786666066706, 44359.77820110903, 44773.552264477345, 44794.55205447945, 44795.32807007898, 44903.0, 9828.312018158731, 9828.459434731087, 9828.852567211497, 9846.261530385233, 9858.507074646275, 8763.912360876373, 8973.32700047499, 11913.583024594142, 12063.215890967103, 12103.818442723323, 11940.7611847763, 11994.280343179393, 12010.219335743192, 12026.218295810744, 12026.338551379671, 8868.556572171366, 11934.820977685342, 11962.641120766304, 11964.0, 11972.341521216329, 11538.0, 11806.0, 11816.231944923553, 12064.457099430547, 12071.336076515789, 41851.58148418538, 42177.15645687069, 42190.578094218974, 42238.78880605608, 42252.73241802751, 2970.8366039867797, 3083.4603944309656, 3128.8435578221224, 3668.4497325401403, 4201.369794530814, 24688.0, 31822.522662159856, 32151.51772723411, 32725.672743272502, 33669.831650841836, 3335.5830521184853, 3374.8818791342187, 3410.2667926395698, 3429.9314013719586, 3453.9654603454146, 518279.0, 521472.0, 521654.0, 522665.0, 523796.0, 61926.380736192965, 62233.066504002476, 62313.0, 62516.68741656263, 62530.687346562976, 65237.476252374385, 65320.673396632716, 65355.34644653588, 65381.673091634235, 65395.67302163459, 16342.75485867713, 16607.920485168666, 16610.252538635792, 16617.252223649724, 16621.83378166203, 11384.0, 11627.883721162849, 12844.422001009976, 12923.353832308536, 12931.612051638465, 14846.851531484763, 15691.058536487848, 16242.431514896922, 16993.065381403892, 17135.05757183367, 5908.645481271137, 6388.8250517081315, 6526.641034743135, 6588.670566471657, 6656.5340426169705, 905.6920646980059, 929.6467342409976, 1036.1658864613908, 1051.453244312953, 1108.8780234174235, 31123.533147002814, 31430.0, 31440.84279578633, 31698.524522132186, 32504.512432313535, 1022.3303736052898, 1105.867295924494, 1123.292325834719, 1140.0822338017808, 1163.7672465506932, 13361.465541378237, 15721.842781572266, 16142.75785863213, 16225.756613650805, 16566.254518546695, 664984.0, 669549.0, 674246.0, 677332.6133369401, 678730.0, 61417.38582614206, 61423.38576614266, 61462.0, 61510.769784604054, 61907.76184476375, 1248.1138391741783, 1376.181172202538, 1399.5381524097024, 1421.118906278073, 1432.7635940069981, 27066.0, 32243.0, 32318.676813231566, 32409.35181296408, 32602.85889993839, 51358.48641513537, 51856.48143518517, 51892.96214075773, 51938.0, 52162.95674086421, 490138.0, 508246.0, 509379.0, 509551.0, 510261.0, 33614.66385336213, 34646.13384665303, 35108.473372899934, 35374.29251415009, 37168.0, 45232.77383613061, 47893.0, 49132.50867491422, 49300.0, 49333.76665583389, 39702.0, 40499.785006449856, 40832.591674082876, 42026.57973420349, 43361.3495797557, 40246.79876600598, 40956.0, 41618.79190604028, 42232.788836055624, 44177.895552611444, 39693.80153099216, 39714.60285397225, 40093.0, 40431.59568404278, 40662.79668601638, 34899.0, 35566.0, 36457.0, 36556.634433656385, 36611.63388366082, 26165.869170654787, 26181.0, 26374.20877373682, 26394.73605263923, 27468.759372187866], "value": 20835.27708956964, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.6403181009851, 390.5645205595762, 394.88745707473373, 399.5485101834926, 413.309772679625, 19646.410607681773, 20393.0, 21214.469638259034, 21216.363509094732, 21725.782742172574, 20884.791152088474, 21066.052027658752, 21256.4685882853, 22082.33752987409, 22109.447263818412, 5516.558675305966, 5925.940740592603, 5946.108083787423, 5948.583599148062, 5978.55160862937, 4143.7720925349095, 4368.148211098837, 5904.468597826195, 6256.906146407785, 6569.967150164266, 6287.717052732638, 6569.113169722098, 6583.177102862142, 6597.175353080865, 6599.439047680953, 6540.836479088013, 6583.308752580958, 6584.6707664616815, 6598.7030583623855, 6613.338666133396, 5201.739913004335, 5479.986202552541, 5498.890022199574, 5733.996550603628, 5735.340435849889, 13637.0, 13891.722165556632, 13963.511277105354, 18829.34097306588, 26740.59889101665, 2105.4104850641693, 2129.350548082834, 2203.900824462887, 2319.5940710375617, 2334.4514039200776, 10237.515560243797, 10345.482725863676, 10412.895871041268, 10515.78968420635, 14084.943629227746, 1593.5617705131065, 1714.7599336092958, 1856.712209607513, 1891.9810801892081, 1919.683252263375, 116812.0, 117232.96602203895, 121371.0, 122218.0, 122598.38700806529, 9998.350107243006, 10856.728581785519, 11475.254108483088, 11743.366811572547, 11866.821997670042, 14595.92702036483, 14693.971422000357, 14694.779578306334, 14714.33785479656, 14728.263586820616, 7624.504407213513, 8178.427510074357, 8395.202455766692, 8630.136986301319, 8696.956515217384, 6848.452123830081, 8496.617652205665, 8523.488590684456, 8630.525321107274, 8652.0, 4546.386237857913, 4572.588467037983, 4605.148047611203, 4671.135839869635, 4692.906141877143, 1542.6606146647719, 1616.9029858208546, 1737.9912252178217, 1767.5139336682257, 1914.9521261968562, 881.8633111867671, 916.331078312822, 941.3363578677016, 958.434523631061, 1005.5123265216511, 20616.38150855477, 20620.381388558366, 21529.67705484419, 21764.45588860291, 21907.452313691967, 866.0, 948.5778828421286, 954.0078318548632, 987.2052997337213, 1018.8675472188568, 8704.695335663222, 11516.0, 11551.76896462066, 11554.342501224766, 11591.420428978518, 616752.0, 645501.0, 659903.0, 670887.0, 671826.0, 132516.67483325236, 135092.64907350997, 136212.0, 137936.31031844777, 138924.0, 1016.2885979814012, 1037.2790910317408, 1052.899974502421, 1054.7468607534136, 1056.3133962924007, 14635.649360653482, 19384.903075485097, 19419.0, 19444.708329375353, 19610.8038919609, 43698.0, 56650.58373540529, 57486.7125664369, 57841.710791445774, 58157.70921145537, 83651.16348836434, 83677.0, 84100.57949710212, 86122.0, 86572.0, 8209.71266005687, 8288.50268983863, 8299.917000830155, 8333.166683331618, 8497.0, 5986.6707331097205, 9325.580348884452, 12321.137520373488, 12607.369631518572, 13254.072214944861, 10690.890990542559, 10904.400257985886, 11335.319880807177, 11545.422728863692, 11561.248518846249, 8862.556872156496, 11392.08863290918, 11424.885751142714, 11663.125265604944, 11695.941520292345, 9759.024097590183, 10792.514336854703, 11247.212695111264, 11548.307101573933, 11936.940315298369, 7452.664630091551, 7846.725364612211, 7892.250236227549, 7913.248241417056, 8062.193780621891, 7803.180666029985, 9421.670241541513, 10085.848712269468, 11661.53353865836, 11662.591809286634], "value": 9534.993345718038, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1320.590616908758, 1531.82384025837, 1718.3470281293107, 1918.434061951724, 1935.4193741877434, 8580.69967551135, 8824.867626985584, 9034.819303613933, 9034.960979487361, 9044.412113212637, 7920.128785833568, 8161.2246836550585, 8172.63223154958, 8275.834483310331, 8844.380893337453, 2144.9249276275295, 2262.264763951714, 2340.3564019894493, 2343.16817529777, 2352.4589344450787, 2358.1039205102124, 2366.7396586375485, 2385.8329916905823, 2397.9760202398015, 2399.2202534176377, 1766.743822145788, 2347.6947996760396, 2358.1628521874745, 2359.7640235976437, 2362.8818559072065, 2290.713660792401, 2380.1669415704364, 2384.9284521464297, 2387.415083304584, 2387.6299173628117, 2387.570237357275, 2396.724376696667, 2413.5414271288573, 2428.490017096412, 2429.0769507587006, 8759.956200219021, 8824.073472285447, 8863.290936725047, 8864.645414183426, 8869.2017718405, 5277.577793776489, 5441.238226648273, 5443.237946687467, 5454.209139674765, 5454.7272636368025, 3528.2237907660274, 3620.475031120473, 3682.355587772129, 3690.1881586051027, 3694.685951694109, 1011.7622358745689, 1066.7386490309846, 1098.7143342730867, 1115.9051480624157, 1119.9552017919273, 10052.798944021151, 11947.98442132411, 11953.983911367544, 11963.983061439789, 11982.221155624944, 4075.2257071156387, 4531.909361812811, 4533.979854532704, 4614.3309220163555, 4713.34013238147, 10227.94886025565, 10242.078212960869, 10275.7431064224, 10279.331843430153, 10286.537105830255, 5358.785648574091, 5429.457054294618, 5430.972845135749, 5431.076716958127, 5434.157705555645, 4734.4081989751285, 4917.188663870493, 4942.9011419771405, 4953.455119936804, 4955.72743499111, 3849.3071247175776, 3856.3444214483607, 3902.453656488094, 3924.9607503924594, 3933.8623148189677, 1070.8768491623407, 1180.701228648497, 1181.7341098252825, 1186.8575770907546, 1190.9523619055142, 860.7030574451804, 893.8659201119884, 918.9494577798151, 962.7593101724569, 1013.716159475348, 5356.946430535722, 5658.490735833712, 6046.818595442144, 6068.180795592627, 6108.786192483241, 433.8481531463962, 435.16448419035527, 440.55723997383063, 443.3814828314483, 464.3174533435844, 4049.271131196413, 4063.695222858238, 4096.09885825124, 4163.791810409467, 4166.479190101238, 10426.530806113591, 10755.0, 10878.129749620011, 10880.0, 10983.560657573767, 10699.89300106989, 10767.89232107669, 10809.297395669257, 10826.837597436044, 10830.404327761891, 6527.51043671717, 7446.367058799954, 8206.466579672302, 8305.086440491541, 8306.958465207756, 5882.470577647861, 5989.131575921379, 5991.550633702577, 6117.510599152146, 6252.3122456530655, 10080.848787268344, 10091.44497052669, 10172.237082219011, 10213.693589192337, 10221.846672299773, 9189.540522973692, 10278.023587759151, 10346.741331466774, 10366.429846358522, 10385.896141038493, 6660.533762636763, 6893.379595836399, 7154.282972086766, 7163.677634506355, 7249.710011599477, 8313.6674533018, 8333.499990000619, 8341.874871877048, 8411.621477033426, 8429.283510901641, 7547.50941188821, 7584.696612135674, 7622.2758837910305, 7638.350740187147, 7732.994710687574, 7446.0, 7482.663280152297, 7506.211847755907, 7631.0, 7633.198514156155, 7078.2921707830865, 7089.468289878383, 7146.964265178641, 7164.462665300019, 7170.928290717235, 5087.898242035212, 5128.58971282304, 5145.331106956071, 5168.147255702767, 5234.947650523446, 5093.694378337312, 5126.974365128151, 5195.792168313225, 5320.334958130234, 5343.225232341211], "value": 4517.719283295925, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.3265351938417, 306.4759261662557, 310.5078450655711, 313.4342511766262, 316.773506942536, 4752.0, 5323.030478722868, 5433.64681295716, 5440.3743569489525, 5444.591655625824, 5348.839534813952, 5397.865053373667, 5421.1297102499675, 5429.348478182614, 5430.70131142787, 3851.5185601799776, 4024.396340548926, 4032.0, 4037.1925614876995, 4048.4534587830562, 3323.435016047266, 3434.8454319555553, 3457.135716070982, 3863.130795571002, 3902.20004898995, 3660.0, 3990.5410877748996, 3995.8601448949294, 4013.1371755072655, 4021.77880216588, 4026.5973402659793, 4032.495938007749, 4049.898752531181, 4055.0398209307077, 4067.877963661065, 3085.861136248852, 3099.333643266686, 3246.2533617267923, 3459.15250763562, 3518.4546395308766, 4442.955570444319, 5166.819161329336, 5167.612429067797, 5185.248139019821, 5257.474252574751, 3846.730429046876, 4583.747893865836, 4626.58360747531, 4636.3045543168455, 4654.976725116387, 5312.990531798946, 5578.828446026339, 5587.653185662031, 5604.1593760935775, 5623.381428042912, 3852.9807350963065, 3887.658757728579, 3887.922241555153, 3888.941665874986, 3929.8231579578987, 5045.41977672565, 5197.998770473358, 5324.520793128637, 5414.862878795458, 5881.441263080001, 3929.206368230754, 3929.5677475477673, 3939.3697008478507, 3973.8211780469946, 3981.2435637229414, 3593.8140413663427, 3850.8844734658005, 4034.3948407738485, 4042.7253924320808, 4044.189288444503, 4276.379924910871, 4624.445066591963, 4640.559146881041, 4658.068386322749, 4693.202155633482, 5151.330327057459, 5635.774569017193, 5647.6893770843, 5658.679941224078, 5663.377028526858, 1205.3551350027692, 1295.4688577683105, 1326.3103186343048, 1351.4121357209644, 1354.1604205392523, 787.5353541410595, 790.7150879820292, 825.3479750996765, 834.5535138700766, 837.4556538250187, 3655.2689462107683, 4113.547509773923, 4417.305443679353, 4446.777661116931, 4513.458384993822, 4171.808566446671, 4571.514257866207, 4577.679562430598, 4807.78364973577, 4962.7022378656675, 3774.509313789245, 4319.870403887889, 4399.986009583446, 4445.2887537993765, 4482.843100491467, 2308.7691230877113, 2396.508715713275, 2432.5013372258645, 2447.865367404774, 2464.6056630938965, 4671.738630569727, 4777.593904518086, 4977.427595826526, 4999.850004499798, 5069.746512674352, 4665.696729712626, 4847.248676455155, 4948.490710333383, 4956.802862153891, 5013.97213571217, 4339.7396156230725, 4553.954460455486, 4744.952550474451, 5055.295416951986, 5064.949350506447, 4352.978235108804, 4512.345709872116, 4826.855194344175, 4855.684380515255, 4920.72935988524, 4387.727559007852, 4436.866893993185, 4511.751853648081, 4867.561919427269, 4868.659193856396, 4795.064962332452, 5041.6470847040355, 5473.917891231555, 5663.2071509989455, 5947.702614869153, 3793.734438589272, 3794.3684215787475, 3800.125971026624, 3823.3309170894427, 3836.5396152461885, 4836.032793441396, 5022.0, 5651.971740141437, 5756.21557317233, 5765.4811067004175, 4940.481249468898, 5243.7152897540345, 5581.7209139543675, 5655.519280861173, 5699.914501282401, 5194.532492075731, 5418.59116629687, 5574.247476590608, 5674.319081710221, 5701.857453563528, 4433.024734558392, 5446.645968012067, 5581.972090139523, 5602.775888964396, 5604.56201552326, 5645.209670646195, 5646.520045796154, 5658.717064146694, 5677.432256774289, 5688.374278829243, 4873.585745211697, 5493.258410114743, 5594.992901277809, 5605.831825045255, 5617.747201375866], "value": 3904.066876419441, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.5797468102272, 753.8718417868962, 767.7332401537466, 836.6444261188993, 844.9281811046059, 14341.713165736679, 14892.702145957075, 15718.685626287468, 16777.748333774987, 16809.411670591548, 13797.310134493286, 13911.582652520441, 13923.094998825069, 13929.651758706037, 15879.285432155551, 889.6530353162289, 894.4990805149138, 907.4600612635471, 909.417972497602, 910.1626503616689, 780.8321210939647, 891.4383938118976, 904.1049361132465, 906.6645341223757, 907.1472815553392, 742.6695120671303, 771.155584634824, 774.7753151586062, 950.9334346595741, 952.9761755956122, 769.9037620297463, 770.9614519274041, 947.346331031586, 991.1624677147798, 992.692265397729, 709.9148102227714, 715.248988562013, 716.8422946951714, 718.0700992215094, 720.4200618502103, 15808.051516909023, 15812.762808557765, 15851.128187949773, 15855.36578536857, 15870.365185392571, 12565.685857853627, 14740.484083057148, 15016.0, 15021.924890375478, 15098.773518397233, 9965.75085622858, 11837.881621183764, 11947.880521194764, 11972.940135299355, 12389.0, 3376.139084533428, 3415.334009868077, 3468.549088618489, 3509.5788505379264, 3566.0728210665156, 99433.5028324861, 100073.4996325021, 100176.49911750395, 100742.48886266712, 103984.0, 10342.431166285776, 11732.237404568676, 11929.58246461387, 11987.160898737005, 12285.69285767863, 24583.262502124966, 24610.138645147334, 24647.630285545732, 24654.506909862062, 24661.0, 8177.182281771775, 8621.69824056155, 8746.037935827055, 8749.518776467228, 8771.561421928878, 4400.185965596375, 4606.262997920317, 4618.30725391194, 4703.694259873166, 4768.904621907543, 3555.5377800885717, 3734.7012239021356, 3741.7380783345448, 3745.7190710696805, 3750.9437358439645, 638.9936100638934, 647.0262255305735, 647.7150053976235, 648.6237981970446, 650.0639079725181, 551.8068675963461, 582.3594046548762, 598.7013244901561, 628.3622123544606, 630.3035146163515, 8704.956475217583, 9011.324150688724, 9060.003399626035, 9121.17909388145, 9245.815083698288, 552.6269767906728, 553.4935533986438, 587.2512546503157, 591.9822405327847, 595.3094410483905, 4428.22506061438, 4437.179121862466, 4445.888852778642, 4464.241079016575, 4470.444948037805, 94040.0, 151003.0, 154950.65234227624, 158697.7338014686, 159073.38995505893, 81498.59250703709, 96458.0, 101533.0, 123537.0043090618, 130228.0, 104869.47565262124, 106721.0, 108133.45933270283, 111042.0, 112708.43645781718, 8540.27407670355, 8542.273906718, 8551.48081149349, 8664.653413863374, 8777.824443510966, 65394.0, 65751.01373479498, 66394.67210655856, 66635.0, 68166.318336816, 83314.0, 97320.0, 97693.0, 98679.50660246653, 99760.50119749646, 11394.829077564009, 11570.363630000431, 11770.823437648613, 11841.644750657493, 11962.461689223832, 12064.75870482568, 12082.879171208175, 12099.637010889688, 12220.205686630341, 12674.112812103065, 11564.666566910933, 11636.0, 11663.475143618727, 11742.119341049285, 12078.879211207775, 9570.712878613653, 11865.762684746083, 11882.643520694393, 11884.940575297414, 12044.879551204376, 11516.366599837089, 11539.134564907497, 11604.593839215588, 11788.70528236801, 11942.522299108286, 9732.80534389294, 9749.658761943298, 9796.951015244878, 9919.305648604817, 10135.493225338556, 9564.033648643965, 10141.797164056825, 11129.849912182479, 11552.364619945647, 11670.591529296435], "value": 7621.127881773075, "unit": "score"}], "name": "dromaeo_css", "value": 7310.188426405056, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 113.0, 113.0, 113.0, 114.0, 111.0, 113.0, 112.0, 114.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 113.0, 106.0, 156.0, 107.0, 110.0, 128.0, 105.0, 138.0, 107.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 186.0, 181.0, 181.0, 184.0, 188.0, 220.0, 185.0, 184.0], "value": 187.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 60.0, 131.0, 80.0, 80.0, 62.0, 59.0, 97.0, 47.0], "value": 77.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 102.0, 101.0, 100.0, 98.0, 97.0, 69.0, 67.0, 100.0], "value": 90.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 85.0, 88.0, 84.0, 88.0, 85.0, 87.0, 85.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 93.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 86.0, 84.0, 86.0, 84.0, 86.0, 84.0, 86.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 53.0, 54.0, 54.0, 53.0, 53.0, 56.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 77.0, 66.0, 75.0, 72.0, 70.0, 68.0, 68.0, 74.0, 100.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 84.0, 80.0, 85.0, 102.0, 100.0, 85.0, 83.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 143.0, 141.0, 125.0, 118.0, 156.0, 124.0, 123.0, 231.0, 142.0], "value": 144.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 160.0, 164.0, 163.0, 160.0, 218.0, 239.0, 164.0, 161.0], "value": 175.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 77.0, 87.0, 80.0, 85.0, 83.0, 66.0], "value": 73.9, "unit": "score"}], "name": "kraken", "value": 1460.6000000000001, "alertThreshold": 2.0}]} 17:45:42 INFO - Return code: 0 17:45:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:45:42 INFO - # TBPL SUCCESS # 17:45:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:45:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:45:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:45:42 INFO - 2016-07-08 17:45:42.933 screenresolution[1937:14502] starting screenresolution argv=screenresolution get 17:45:42 INFO - 2016-07-08 17:45:42.936 screenresolution[1937:14502] Display 0: 1600x1200x32@60 17:45:42 INFO - 2016-07-08 17:45:42.942 screenresolution[1938:14505] starting screenresolution argv=screenresolution list 17:45:42 INFO - Available Modes on Display 0 17:45:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:45:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:45:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:45:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:45:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:45:43 INFO - Intel Iris: 17:45:43 INFO - Chipset Model: Intel Iris 17:45:43 INFO - Type: GPU 17:45:43 INFO - Bus: Built-In 17:45:43 INFO - VRAM (Dynamic, Max): 1536 MB 17:45:43 INFO - Vendor: Intel (0x8086) 17:45:43 INFO - Device ID: 0x0a2e 17:45:43 INFO - Revision ID: 0x0009 17:45:43 INFO - Displays: 17:45:43 INFO - Display: 17:45:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:45:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:45:43 INFO - Display Serial Number: 1600x1200 60 17:45:43 INFO - Main Display: Yes 17:45:43 INFO - Mirror: Off 17:45:43 INFO - Online: Yes 17:45:43 INFO - Rotation: Supported 17:45:43 INFO - Return code: 0 17:45:43 INFO - Running post-action listener: _resource_record_post_action 17:45:43 INFO - ##### 17:45:43 INFO - ##### Finished run-tests step (success) 17:45:43 INFO - ##### 17:45:43 INFO - Running post-run listener: _resource_record_post_run 17:45:43 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 839680; Write bytes: 704223232; Read time: 13; Write time: 2005 17:45:43 INFO - TinderboxPrint: CPU usage
53.8% 17:45:43 INFO - TinderboxPrint: I/O read bytes / time
839,680 / 13 17:45:43 INFO - TinderboxPrint: I/O write bytes / time
704,223,232 / 2,005 17:45:43 INFO - TinderboxPrint: CPU idle
2,239.8 (46.2%) 17:45:43 INFO - TinderboxPrint: CPU system
973.8 (20.1%) 17:45:43 INFO - TinderboxPrint: CPU user
1,632.1 (33.7%) 17:45:43 INFO - TinderboxPrint: Swap in / out
408,248,320 / 0 17:45:43 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 390083072; Write bytes: 384796672; Read time: 29100; Write time: 707 17:45:43 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 839680; Write bytes: 294293504; Read time: 13; Write time: 1251 17:45:43 INFO - Running post-run listener: _upload_blobber_files 17:45:43 INFO - Blob upload gear active. 17:45:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:45:43 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 17:45:43 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:45:43 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:45:44 INFO - (blobuploader) - INFO - Open directory for files ... 17:45:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:45:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:45:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:44 INFO - (blobuploader) - INFO - Done attempting. 17:45:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:45:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:45:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:45 INFO - (blobuploader) - INFO - Done attempting. 17:45:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:45:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:45:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:47 INFO - (blobuploader) - INFO - Done attempting. 17:45:47 INFO - (blobuploader) - INFO - Iteration through files over. 17:45:48 INFO - Return code: 0 17:45:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:45:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:45:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/715dae7c3ea0538f3e5f1e7976b77d23a9223ea8197114b75df28e6f7782322fca62564e17073069032e53796d2efcbca1b244616827bcf861cc25123dd9a015", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9643fbc16069dc39465a58f52c2406cec526868717237bb1eaf71fc16c012b3adf516ddb27be62267b05d40426450d0cc083b89655225b6fc323d25748a5a539", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abc7e6a78045595a700875a37d0d93078273df34fd299617bef7d6e2755e014dfdb8f80059b0197a2dbb86944ff2ebfb67197ebac8fce3edf8e2aa114f094204"} 17:45:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:45:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:45:48 INFO - Contents: 17:45:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/715dae7c3ea0538f3e5f1e7976b77d23a9223ea8197114b75df28e6f7782322fca62564e17073069032e53796d2efcbca1b244616827bcf861cc25123dd9a015", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9643fbc16069dc39465a58f52c2406cec526868717237bb1eaf71fc16c012b3adf516ddb27be62267b05d40426450d0cc083b89655225b6fc323d25748a5a539", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abc7e6a78045595a700875a37d0d93078273df34fd299617bef7d6e2755e014dfdb8f80059b0197a2dbb86944ff2ebfb67197ebac8fce3edf8e2aa114f094204"} 17:45:48 INFO - Running post-run listener: copy_logs_to_upload_dir 17:45:48 INFO - Copying logs to upload dir... 17:45:48 INFO - mkdir: /builds/slave/test/build/upload/logs 17:45:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.306190 ========= master_lag: 4.95 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-08 17:45:52.981272) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 17:45:52.984342) ========= 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.MTf4YgWEL8/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.cdqBYGlzie/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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/Firefox/sha512/715dae7c3ea0538f3e5f1e7976b77d23a9223ea8197114b75df28e6f7782322fca62564e17073069032e53796d2efcbca1b244616827bcf861cc25123dd9a015", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9643fbc16069dc39465a58f52c2406cec526868717237bb1eaf71fc16c012b3adf516ddb27be62267b05d40426450d0cc083b89655225b6fc323d25748a5a539", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abc7e6a78045595a700875a37d0d93078273df34fd299617bef7d6e2755e014dfdb8f80059b0197a2dbb86944ff2ebfb67197ebac8fce3edf8e2aa114f094204"} build_url:https://queue.taskcluster.net/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011116 build_url: 'https://queue.taskcluster.net/v1/task/A0txUtkLRq67XdDXxuZfGw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/715dae7c3ea0538f3e5f1e7976b77d23a9223ea8197114b75df28e6f7782322fca62564e17073069032e53796d2efcbca1b244616827bcf861cc25123dd9a015", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9643fbc16069dc39465a58f52c2406cec526868717237bb1eaf71fc16c012b3adf516ddb27be62267b05d40426450d0cc083b89655225b6fc323d25748a5a539", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abc7e6a78045595a700875a37d0d93078273df34fd299617bef7d6e2755e014dfdb8f80059b0197a2dbb86944ff2ebfb67197ebac8fce3edf8e2aa114f094204"}' ========= master_lag: 0.37 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 17:45:53.370439) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 8 secs) (at 2016-07-08 17:45:53.370731) ========= 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.MTf4YgWEL8/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.cdqBYGlzie/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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.005125 ========= master_lag: 8.81 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 8 secs) (at 2016-07-08 17:46:02.186404) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 17:46:02.186725) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 17:46:02.196279) ========= ========= Total master_lag: 14.21 =========