builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0109 starttime: 1462025853.57 results: success (0) buildid: 20160430051339 builduid: 142b00fc01194d06afe3a09b60593ffa revision: 3f169c95e542f059b6b07d11e7f73533bdbf03d6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-30 07:17:33.574575) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-30 07:17:33.575025) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 39 secs) (at 2016-04-30 07:17:33.575331) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 39 secs) (at 2016-04-30 07:18:12.729443) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-30 07:18:12.729699) ========= 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.mb7JN4myA3/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.azen0aCO5y/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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-04-30 07:18:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.2M=0.001s 2016-04-30 07:18:14 (18.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.597917 ========= master_lag: 2.32 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-30 07:18:15.646769) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-30 07:18:15.647081) ========= 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.mb7JN4myA3/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.azen0aCO5y/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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.027095 ========= master_lag: 1.60 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-30 07:18:17.273322) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-04-30 07:18:17.273638) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3f169c95e542f059b6b07d11e7f73533bdbf03d6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3f169c95e542f059b6b07d11e7f73533bdbf03d6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mb7JN4myA3/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.azen0aCO5y/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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-04-30 07:18:18,272 truncating revision to first 12 chars 2016-04-30 07:18:18,272 Setting DEBUG logging. 2016-04-30 07:18:18,272 attempt 1/10 2016-04-30 07:18:18,272 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3f169c95e542?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-30 07:18:18,984 unpacking tar archive at: mozilla-aurora-3f169c95e542/testing/mozharness/ program finished with exit code 0 elapsedTime=0.963991 ========= master_lag: 6.75 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-04-30 07:18:24.991529) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-30 07:18:24.991796) ========= script_repo_revision: 3f169c95e542f059b6b07d11e7f73533bdbf03d6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-30 07:18:24.992233) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 11 secs) (at 2016-04-30 07:18:24.992520) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 11 secs) (at 2016-04-30 07:18:36.869532) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-04-30 07:18:36.869915) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mb7JN4myA3/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.azen0aCO5y/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 07:18:38 INFO - MultiFileLogger online at 20160430 07:18:38 in /builds/slave/test 07:18:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:18:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:18:38 INFO - {'append_to_log': False, 07:18:38 INFO - 'base_work_dir': '/builds/slave/test', 07:18:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:18:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:18:38 INFO - 'branch': 'Mozilla-Aurora', 07:18:38 INFO - 'buildbot_json_path': 'buildprops.json', 07:18:38 INFO - 'config_files': ('talos/mac_config.py',), 07:18:38 INFO - 'default_actions': ('clobber', 07:18:38 INFO - 'read-buildbot-config', 07:18:38 INFO - 'download-and-extract', 07:18:38 INFO - 'populate-webroot', 07:18:38 INFO - 'create-virtualenv', 07:18:38 INFO - 'install', 07:18:38 INFO - 'run-tests'), 07:18:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:18:38 INFO - 'download_minidump_stackwalk': True, 07:18:38 INFO - 'download_symbols': 'ondemand', 07:18:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:18:38 INFO - 'tooltool.py': '/tools/tooltool.py', 07:18:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:18:38 INFO - '/tools/misc-python/virtualenv.py')}, 07:18:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:18:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:18:38 INFO - 'installer_path': 'installer.exe', 07:18:38 INFO - 'log_level': 'info', 07:18:38 INFO - 'log_name': 'talos', 07:18:38 INFO - 'log_to_console': True, 07:18:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:18:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:18:38 INFO - 'opt_config_files': (), 07:18:38 INFO - 'pip_index': False, 07:18:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:38 INFO - 'cmd': ('bash', 07:18:38 INFO - '-c', 07:18:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:38 INFO - 'enabled': True, 07:18:38 INFO - 'halt_on_failure': False, 07:18:38 INFO - 'name': 'check_screen_resolution'},), 07:18:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:38 INFO - 'cmd': ('bash', 07:18:38 INFO - '-c', 07:18:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:38 INFO - 'enabled': True, 07:18:38 INFO - 'halt_on_failure': False, 07:18:38 INFO - 'name': 'check_screen_resolution'},), 07:18:38 INFO - 'run_cmd_checks_enabled': True, 07:18:38 INFO - 'sps_profile': False, 07:18:38 INFO - 'sps_profile_interval': 0, 07:18:38 INFO - 'suite': 'dromaeojs', 07:18:38 INFO - 'system_bits': '32', 07:18:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:18:38 INFO - 'title': 't-yosemite-r7-0109', 07:18:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:18:38 INFO - 'use_talos_json': True, 07:18:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:18:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:18:38 INFO - 'work_dir': 'build'} 07:18:38 INFO - ##### 07:18:38 INFO - ##### Running clobber step. 07:18:38 INFO - ##### 07:18:38 INFO - Running pre-action listener: _resource_record_pre_action 07:18:38 INFO - Running main action method: clobber 07:18:38 INFO - rmtree: /builds/slave/test/build 07:18:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:18:40 INFO - Running post-action listener: _resource_record_post_action 07:18:40 INFO - ##### 07:18:40 INFO - ##### Running read-buildbot-config step. 07:18:40 INFO - ##### 07:18:40 INFO - Running pre-action listener: _resource_record_pre_action 07:18:40 INFO - Running main action method: read_buildbot_config 07:18:40 INFO - Using buildbot properties: 07:18:40 INFO - { 07:18:40 INFO - "project": "", 07:18:40 INFO - "product": "firefox", 07:18:40 INFO - "script_repo_revision": "production", 07:18:40 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:18:40 INFO - "repository": "", 07:18:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:18:40 INFO - "buildid": "20160430051339", 07:18:40 INFO - "pgo_build": "False", 07:18:40 INFO - "basedir": "/builds/slave/test", 07:18:40 INFO - "buildnumber": 333, 07:18:40 INFO - "slavename": "t-yosemite-r7-0109", 07:18:40 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:18:40 INFO - "platform": "yosemite_r7", 07:18:40 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:18:40 INFO - "branch": "mozilla-aurora", 07:18:40 INFO - "repo_path": "releases/mozilla-aurora", 07:18:40 INFO - "revision": "3f169c95e542f059b6b07d11e7f73533bdbf03d6", 07:18:40 INFO - "stage_platform": "macosx64", 07:18:40 INFO - "builduid": "142b00fc01194d06afe3a09b60593ffa", 07:18:40 INFO - "slavebuilddir": "test" 07:18:40 INFO - } 07:18:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 07:18:40 INFO - Running post-action listener: _resource_record_post_action 07:18:40 INFO - ##### 07:18:40 INFO - ##### Running download-and-extract step. 07:18:40 INFO - ##### 07:18:40 INFO - Running pre-action listener: _resource_record_pre_action 07:18:40 INFO - Running main action method: download_and_extract 07:18:40 INFO - mkdir: /builds/slave/test/build/tests 07:18:40 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')]} 07:18:40 INFO - https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:18:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 07:18:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 07:18:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 07:18:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 07:18:45 INFO - Downloaded 1199 bytes. 07:18:45 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 07:18:45 INFO - Using the following test package requirements: 07:18:45 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 07:18:45 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:18:45 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 07:18:45 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:18:45 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 07:18:45 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:18:45 INFO - u'jsshell-mac.zip'], 07:18:45 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:18:45 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 07:18:45 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 07:18:45 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:18:45 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 07:18:45 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:18:45 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 07:18:45 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:18:45 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 07:18:45 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:18:45 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 07:18:45 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:18: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')]} 07:18:45 INFO - https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:18:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:18:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:18:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:18:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:18:47 INFO - Downloaded 18110494 bytes. 07:18:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:18:50 INFO - Return code: 0 07:18:50 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 07:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:50 INFO - https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:18:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:18:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:18:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:18:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:18:51 INFO - Downloaded 18110494 bytes. 07:18:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:18:55 INFO - Return code: 0 07:18: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')]} 07:18:55 INFO - https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:18:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 07:18:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 07:18:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 07:18:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:18:55 INFO - Downloaded 10840145 bytes. 07:18:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 07:18:56 INFO - Return code: 0 07:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:56 INFO - https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 07:18:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 07:18:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 07:18:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 07:18:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 07:19:00 INFO - Downloaded 114073247 bytes. 07:19:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 07:19:00 INFO - mkdir: /builds/slave/test/properties 07:19:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:19:00 INFO - Writing to file /builds/slave/test/properties/build_url 07:19:00 INFO - Contents: 07:19:00 INFO - build_url:https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 07:19:01 INFO - Running post-action listener: _resource_record_post_action 07:19:01 INFO - Running post-action listener: set_extra_try_arguments 07:19:01 INFO - ##### 07:19:01 INFO - ##### Running populate-webroot step. 07:19:01 INFO - ##### 07:19:01 INFO - Running pre-action listener: _resource_record_pre_action 07:19:01 INFO - Running main action method: populate_webroot 07:19:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:19:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:19:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:19:01 INFO - '--tppagecycles', 07:19:01 INFO - '7'], 07:19:01 INFO - 'tests': ['tsvgm']}}, 07:19:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:19:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:19:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:19:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:19:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:19:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:19:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:19:01 INFO - 'talos_options': ['--disable-e10s'], 07:19:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:19:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:19:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:19:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:19:01 INFO - 'talos_options': ['--disable-e10s'], 07:19:01 INFO - 'tests': ['damp', 'tps']}, 07:19:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:19:01 INFO - 'tests': ['damp', 'tps']}, 07:19:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:19:01 INFO - 'tests': ['dromaeo_dom']}, 07:19:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:19:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:19:01 INFO - 'tests': ['a11yr', 07:19:01 INFO - 'ts_paint', 07:19:01 INFO - 'tpaint', 07:19:01 INFO - 'sessionrestore', 07:19:01 INFO - 'sessionrestore_no_auto_restore', 07:19:01 INFO - 'tabpaint']}, 07:19:01 INFO - 'other-e10s': {'tests': ['a11yr', 07:19:01 INFO - 'ts_paint', 07:19:01 INFO - 'tpaint', 07:19:01 INFO - 'sessionrestore', 07:19:01 INFO - 'sessionrestore_no_auto_restore', 07:19:01 INFO - 'tabpaint']}, 07:19:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:19:01 INFO - 'ts_paint', 07:19:01 INFO - 'tpaint', 07:19:01 INFO - 'sessionrestore', 07:19:01 INFO - 'sessionrestore_no_auto_restore', 07:19:01 INFO - 'tabpaint']}, 07:19:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:19:01 INFO - 'ts_paint', 07:19:01 INFO - 'tpaint', 07:19:01 INFO - 'sessionrestore', 07:19:01 INFO - 'sessionrestore_no_auto_restore', 07:19:01 INFO - 'tabpaint']}, 07:19:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:19:01 INFO - 'tests': ['a11yr', 07:19:01 INFO - 'ts_paint', 07:19:01 INFO - 'tpaint', 07:19:01 INFO - 'sessionrestore', 07:19:01 INFO - 'sessionrestore_no_auto_restore', 07:19:01 INFO - 'tabpaint']}, 07:19:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:19:01 INFO - 'tests': ['a11yr', 07:19:01 INFO - 'ts_paint', 07:19:01 INFO - 'tpaint', 07:19:01 INFO - 'sessionrestore', 07:19:01 INFO - 'sessionrestore_no_auto_restore', 07:19:01 INFO - 'tabpaint']}, 07:19:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:19:01 INFO - 'tests': ['tsvgx', 07:19:01 INFO - 'tsvgr_opacity', 07:19:01 INFO - 'tart', 07:19:01 INFO - 'tscrollx', 07:19:01 INFO - 'cart']}, 07:19:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:19:01 INFO - 'tsvgr_opacity', 07:19:01 INFO - 'tart', 07:19:01 INFO - 'tscrollx', 07:19:01 INFO - 'cart']}, 07:19:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:19:01 INFO - 'talos_options': ['--disable-e10s'], 07:19:01 INFO - 'tests': ['tp5o']}, 07:19:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:19:01 INFO - 'tests': ['tp5o']}, 07:19:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:19:01 INFO - 'talos_options': ['--disable-e10s', 07:19:01 INFO - '--xperf_path', 07:19:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:19:01 INFO - 'tests': ['tp5n']}, 07:19:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:19:01 INFO - 'talos_options': ['--xperf_path', 07:19:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:19:01 INFO - 'tests': ['tp5n']}}, 07:19:01 INFO - 'talos.zip': {'path': '', 07:19:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:19:01 INFO - Running post-action listener: _resource_record_post_action 07:19:01 INFO - ##### 07:19:01 INFO - ##### Running create-virtualenv step. 07:19:01 INFO - ##### 07:19:01 INFO - Running pre-action listener: _resource_record_pre_action 07:19:01 INFO - Running main action method: create_virtualenv 07:19:01 INFO - Creating virtualenv /builds/slave/test/build/venv 07:19: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 07:19:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:19:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:19:01 INFO - Using real prefix '/tools/python27' 07:19:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:19:02 INFO - Installing distribute.............................................................................................................................................................................................done. 07:19:05 INFO - Installing pip.................done. 07:19:05 INFO - Return code: 0 07:19:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:19: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')]} 07:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19: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')]} 07:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19: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 0x10b73ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbea56cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dbe00>, '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 0x10b444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d6b20>, '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.mb7JN4myA3/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.azen0aCO5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 07:19: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 07:19: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 07:19:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mb7JN4myA3/Render', 07:19:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:05 INFO - 'HOME': '/Users/cltbld', 07:19:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:05 INFO - 'LOGNAME': 'cltbld', 07:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:05 INFO - 'MOZ_NO_REMOTE': '1', 07:19:05 INFO - 'NO_EM_RESTART': '1', 07:19:05 INFO - 'PAGER': '/bin/cat', 07:19:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:05 INFO - 'PWD': '/builds/slave/test', 07:19:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:05 INFO - 'SHELL': '/bin/bash', 07:19:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.azen0aCO5y/Listeners', 07:19:05 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 07:19:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:05 INFO - 'USER': 'cltbld', 07:19:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:05 INFO - 'XPC_FLAGS': '0x0', 07:19:05 INFO - 'XPC_SERVICE_NAME': '0', 07:19:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19: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 07:19:05 INFO - Cleaning up... 07:19:05 INFO - Return code: 0 07:19:05 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:19: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')]} 07:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19: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')]} 07:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19: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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b73ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbea56cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dbe00>, '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 0x10b444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d6b20>, '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.mb7JN4myA3/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.azen0aCO5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 07:19: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>=0.7.1'] in /builds/slave/test/build 07:19: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>=0.7.1 07:19:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mb7JN4myA3/Render', 07:19:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:05 INFO - 'HOME': '/Users/cltbld', 07:19:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:05 INFO - 'LOGNAME': 'cltbld', 07:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:05 INFO - 'MOZ_NO_REMOTE': '1', 07:19:05 INFO - 'NO_EM_RESTART': '1', 07:19:05 INFO - 'PAGER': '/bin/cat', 07:19:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:05 INFO - 'PWD': '/builds/slave/test', 07:19:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:05 INFO - 'SHELL': '/bin/bash', 07:19:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.azen0aCO5y/Listeners', 07:19:05 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 07:19:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:05 INFO - 'USER': 'cltbld', 07:19:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:05 INFO - 'XPC_FLAGS': '0x0', 07:19:05 INFO - 'XPC_SERVICE_NAME': '0', 07:19:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:05 INFO - Downloading/unpacking psutil>=0.7.1 07:19: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 07:19: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 07:19: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 07:19: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 07:19: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 07:19: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 07:19:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:19:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:19:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:19:08 INFO - Installing collected packages: psutil 07:19:08 INFO - Running setup.py install for psutil 07:19:08 INFO - building 'psutil._psutil_osx' extension 07:19: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 07:19: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 07:19: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 07:19: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 07:19:09 INFO - building 'psutil._psutil_posix' extension 07:19: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 07:19:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:19:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:19:09 INFO - ^ 07:19:09 INFO - 1 warning generated. 07:19: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 07:19:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:19:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:19:09 INFO - Successfully installed psutil 07:19:09 INFO - Cleaning up... 07:19:09 INFO - Return code: 0 07:19:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:19: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')]} 07:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19: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')]} 07:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19: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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b73ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbea56cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dbe00>, '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 0x10b444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d6b20>, '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.mb7JN4myA3/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.azen0aCO5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 07:19: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.0.0'] in /builds/slave/test/build 07:19: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.0.0 07:19:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mb7JN4myA3/Render', 07:19:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:09 INFO - 'HOME': '/Users/cltbld', 07:19:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:09 INFO - 'LOGNAME': 'cltbld', 07:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:09 INFO - 'MOZ_NO_REMOTE': '1', 07:19:09 INFO - 'NO_EM_RESTART': '1', 07:19:09 INFO - 'PAGER': '/bin/cat', 07:19:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:09 INFO - 'PWD': '/builds/slave/test', 07:19:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:09 INFO - 'SHELL': '/bin/bash', 07:19:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.azen0aCO5y/Listeners', 07:19:09 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 07:19:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:09 INFO - 'USER': 'cltbld', 07:19:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:09 INFO - 'XPC_FLAGS': '0x0', 07:19:09 INFO - 'XPC_SERVICE_NAME': '0', 07:19:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:19:10 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 07:19:10 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 07:19:10 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 07:19:10 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 07:19:10 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 07:19:10 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 07:19:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:19:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:19:12 INFO - Installing collected packages: mozsystemmonitor 07:19:12 INFO - Running setup.py install for mozsystemmonitor 07:19:12 INFO - Successfully installed mozsystemmonitor 07:19:12 INFO - Cleaning up... 07:19:12 INFO - Return code: 0 07:19:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:19: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')]} 07:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19: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')]} 07:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19: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', '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 0x10b73ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbea56cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dbe00>, '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 0x10b444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d6b20>, '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.mb7JN4myA3/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.azen0aCO5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 07:19: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:19: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 blobuploader==1.2.4 07:19:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mb7JN4myA3/Render', 07:19:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:12 INFO - 'HOME': '/Users/cltbld', 07:19:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:12 INFO - 'LOGNAME': 'cltbld', 07:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:12 INFO - 'MOZ_NO_REMOTE': '1', 07:19:12 INFO - 'NO_EM_RESTART': '1', 07:19:12 INFO - 'PAGER': '/bin/cat', 07:19:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:12 INFO - 'PWD': '/builds/slave/test', 07:19:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:12 INFO - 'SHELL': '/bin/bash', 07:19:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.azen0aCO5y/Listeners', 07:19:12 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 07:19:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:12 INFO - 'USER': 'cltbld', 07:19:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:12 INFO - 'XPC_FLAGS': '0x0', 07:19:12 INFO - 'XPC_SERVICE_NAME': '0', 07:19:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:13 INFO - Downloading/unpacking blobuploader==1.2.4 07:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:15 INFO - Downloading blobuploader-1.2.4.tar.gz 07:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:19:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:19: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 07:19: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 07:19: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 07:19: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 07:19: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 07:19: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 07:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:19:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:19: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 07:19: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 07:19: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 07:19: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 07:19: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 07:19: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 07:19:16 INFO - Downloading docopt-0.6.1.tar.gz 07:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:19:16 INFO - Installing collected packages: blobuploader, requests, docopt 07:19:16 INFO - Running setup.py install for blobuploader 07:19:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:19:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:19:16 INFO - Running setup.py install for requests 07:19:17 INFO - Running setup.py install for docopt 07:19:17 INFO - Successfully installed blobuploader requests docopt 07:19:17 INFO - Cleaning up... 07:19:17 INFO - Return code: 0 07:19:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:17 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 0x10b73ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbea56cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dbe00>, '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 0x10b444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d6b20>, '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.mb7JN4myA3/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.azen0aCO5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 07:19:17 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 07:19:17 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 07:19:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mb7JN4myA3/Render', 07:19:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:17 INFO - 'HOME': '/Users/cltbld', 07:19:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:17 INFO - 'LOGNAME': 'cltbld', 07:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:17 INFO - 'MOZ_NO_REMOTE': '1', 07:19:17 INFO - 'NO_EM_RESTART': '1', 07:19:17 INFO - 'PAGER': '/bin/cat', 07:19:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:17 INFO - 'PWD': '/builds/slave/test', 07:19:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:17 INFO - 'SHELL': '/bin/bash', 07:19:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.azen0aCO5y/Listeners', 07:19:17 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 07:19:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:17 INFO - 'USER': 'cltbld', 07:19:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:17 INFO - 'XPC_FLAGS': '0x0', 07:19:17 INFO - 'XPC_SERVICE_NAME': '0', 07:19:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:19:17 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-WJETMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:19:17 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-objYhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:19:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-EGCv5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:19:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-S_MbBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:19:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-dkdtEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:19:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-mapVGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:19:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-doLNQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:19:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-CYtXTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:19:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-YAYFXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:19:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-GfYj1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:19:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-mI2238-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:19:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-CzfWg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:19:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-yDQznw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:19:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-oA3fWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:19:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-_1mVy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:19:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-jACSOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:19:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-cpuX5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:19:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:19:19 INFO - Running setup.py install for manifestparser 07:19:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:19:19 INFO - Running setup.py install for mozcrash 07:19:19 INFO - Running setup.py install for mozdebug 07:19:20 INFO - Running setup.py install for mozdevice 07:19:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:19:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:19:20 INFO - Running setup.py install for mozfile 07:19:20 INFO - Running setup.py install for mozhttpd 07:19:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:19:20 INFO - Running setup.py install for mozinfo 07:19:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:19:20 INFO - Running setup.py install for mozInstall 07:19:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:19:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:19:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:19:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:19:20 INFO - Running setup.py install for mozleak 07:19:20 INFO - Running setup.py install for mozlog 07:19:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:19:21 INFO - Running setup.py install for moznetwork 07:19:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:19:21 INFO - Running setup.py install for mozprocess 07:19:21 INFO - Running setup.py install for mozprofile 07:19:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:19:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:19:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:19:21 INFO - Running setup.py install for mozrunner 07:19:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:19:21 INFO - Running setup.py install for mozscreenshot 07:19:21 INFO - Running setup.py install for moztest 07:19:22 INFO - Running setup.py install for mozversion 07:19:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:19:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:19:22 INFO - Cleaning up... 07:19:22 INFO - Return code: 0 07:19:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:19:22 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')]} 07:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:22 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')]} 07:19:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:22 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 0x10b73ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbea56cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dbe00>, '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 0x10b444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d6b20>, '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.mb7JN4myA3/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.azen0aCO5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 07:19:22 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 07:19:22 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 07:19:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mb7JN4myA3/Render', 07:19:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:22 INFO - 'HOME': '/Users/cltbld', 07:19:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:22 INFO - 'LOGNAME': 'cltbld', 07:19:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:22 INFO - 'MOZ_NO_REMOTE': '1', 07:19:22 INFO - 'NO_EM_RESTART': '1', 07:19:22 INFO - 'PAGER': '/bin/cat', 07:19:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:22 INFO - 'PWD': '/builds/slave/test', 07:19:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:22 INFO - 'SHELL': '/bin/bash', 07:19:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.azen0aCO5y/Listeners', 07:19:22 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 07:19:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:22 INFO - 'USER': 'cltbld', 07:19:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:22 INFO - 'XPC_FLAGS': '0x0', 07:19:22 INFO - 'XPC_SERVICE_NAME': '0', 07:19:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:19:22 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-dZHtjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:19:22 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)) 07:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:19:22 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-tD8Za9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:19:22 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)) 07:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:19:22 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-eh7Pb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:19:22 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)) 07:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:19:22 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-oKr81U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:19:22 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)) 07:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:19:22 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-oQLWmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:19:23 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)) 07:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:19:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-zVR9oX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:19:23 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)) 07:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:19:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-qHdVD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:19:23 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)) 07:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:19:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-7DoJnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:19:23 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)) 07:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:19:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-4GRaJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:19:23 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)) 07:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:19:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-lvXn2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 07:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:19:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-8f3QZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:19:23 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)) 07:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:19:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-m0WCA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:19:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-Wp6ait-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:19:23 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)) 07:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:19:23 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-h50BKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:19:24 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-6xesBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:19:24 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)) 07:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:19:24 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-gzyJWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:19:24 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)) 07:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:19:24 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-k1xlKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:19:24 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)) 07:19:24 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)) 07:19:24 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)) 07:19:24 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)) 07:19:24 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)) 07:19:24 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)) 07:19:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:26 INFO - Downloading blessings-1.6.tar.gz 07:19:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:19:26 INFO - Installing collected packages: blessings 07:19:26 INFO - Running setup.py install for blessings 07:19:26 INFO - Successfully installed blessings 07:19:26 INFO - Cleaning up... 07:19:27 INFO - Return code: 0 07:19:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:19:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:19:27 INFO - Reading from file tmpfile_stdout 07:19:27 INFO - Current package versions: 07:19:27 INFO - blessings == 1.6 07:19:27 INFO - blobuploader == 1.2.4 07:19:27 INFO - docopt == 0.6.1 07:19:27 INFO - manifestparser == 1.1 07:19:27 INFO - mozInstall == 1.12 07:19:27 INFO - mozcrash == 0.17 07:19:27 INFO - mozdebug == 0.1 07:19:27 INFO - mozdevice == 0.48 07:19:27 INFO - mozfile == 1.2 07:19:27 INFO - mozhttpd == 0.7 07:19:27 INFO - mozinfo == 0.9 07:19:27 INFO - mozleak == 0.1 07:19:27 INFO - mozlog == 3.1 07:19:27 INFO - moznetwork == 0.27 07:19:27 INFO - mozprocess == 0.22 07:19:27 INFO - mozprofile == 0.28 07:19:27 INFO - mozrunner == 6.11 07:19:27 INFO - mozscreenshot == 0.1 07:19:27 INFO - mozsystemmonitor == 0.0 07:19:27 INFO - moztest == 0.7 07:19:27 INFO - mozversion == 1.4 07:19:27 INFO - psutil == 3.1.1 07:19:27 INFO - requests == 1.2.3 07:19:27 INFO - wsgiref == 0.1.2 07:19:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:27 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 0x10b73ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbea56cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dbe00>, '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 0x10b444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d6b20>, '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.mb7JN4myA3/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.azen0aCO5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 07:19:27 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 07:19:27 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 07:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mb7JN4myA3/Render', 07:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:27 INFO - 'HOME': '/Users/cltbld', 07:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:27 INFO - 'LOGNAME': 'cltbld', 07:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:27 INFO - 'MOZ_NO_REMOTE': '1', 07:19:27 INFO - 'NO_EM_RESTART': '1', 07:19:27 INFO - 'PAGER': '/bin/cat', 07:19:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:27 INFO - 'PWD': '/builds/slave/test', 07:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:27 INFO - 'SHELL': '/bin/bash', 07:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.azen0aCO5y/Listeners', 07:19:27 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 07:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:27 INFO - 'USER': 'cltbld', 07:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:27 INFO - 'XPC_FLAGS': '0x0', 07:19:27 INFO - 'XPC_SERVICE_NAME': '0', 07:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:27 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)) 07:19:27 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)) 07:19:27 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)) 07:19:27 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)) 07:19:27 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)) 07:19:27 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)) 07:19:27 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)) 07:19:27 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)) 07:19:27 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)) 07:19:27 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)) 07:19:27 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)) 07:19:27 INFO - Cleaning up... 07:19:27 INFO - Return code: 0 07:19:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b73ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbea56cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dbe00>, '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 0x10b444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d6b20>, '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.mb7JN4myA3/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.azen0aCO5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 07:19:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mb7JN4myA3/Render', 07:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:27 INFO - 'HOME': '/Users/cltbld', 07:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:27 INFO - 'LOGNAME': 'cltbld', 07:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:27 INFO - 'MOZ_NO_REMOTE': '1', 07:19:27 INFO - 'NO_EM_RESTART': '1', 07:19:27 INFO - 'PAGER': '/bin/cat', 07:19:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:27 INFO - 'PWD': '/builds/slave/test', 07:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:27 INFO - 'SHELL': '/bin/bash', 07:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.azen0aCO5y/Listeners', 07:19:27 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 07:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:27 INFO - 'USER': 'cltbld', 07:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:27 INFO - 'XPC_FLAGS': '0x0', 07:19:27 INFO - 'XPC_SERVICE_NAME': '0', 07:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:27 INFO - Downloading/unpacking jsonschema 07:19: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 07:19: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 07:19: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 07:19: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 07:19: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 07:19: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 07:19:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:19:30 INFO - Downloading/unpacking functools32 (from jsonschema) 07:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:30 INFO - Downloading functools32-3.2.3-2.tar.gz 07:19:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:19:30 INFO - warning: no files found matching '*.txt' 07:19:30 INFO - no previously-included directories found matching 'build' 07:19:30 INFO - no previously-included directories found matching 'dist' 07:19:30 INFO - no previously-included directories found matching '.git*' 07:19:30 INFO - Installing collected packages: jsonschema, functools32 07:19:30 INFO - Running setup.py install for functools32 07:19:30 INFO - warning: no files found matching '*.txt' 07:19:30 INFO - no previously-included directories found matching 'build' 07:19:30 INFO - no previously-included directories found matching 'dist' 07:19:30 INFO - no previously-included directories found matching '.git*' 07:19:30 INFO - Successfully installed jsonschema functools32 07:19:30 INFO - Cleaning up... 07:19:31 INFO - Return code: 0 07:19:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:19: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')]} 07:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19: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')]} 07:19:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b73ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbea56cfb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dbe00>, '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 0x10b444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d6b20>, '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.mb7JN4myA3/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.azen0aCO5y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 07:19: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', 'flake8'] in /builds/slave/test/build 07:19: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 flake8 07:19:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mb7JN4myA3/Render', 07:19:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:31 INFO - 'HOME': '/Users/cltbld', 07:19:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:31 INFO - 'LOGNAME': 'cltbld', 07:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:31 INFO - 'MOZ_NO_REMOTE': '1', 07:19:31 INFO - 'NO_EM_RESTART': '1', 07:19:31 INFO - 'PAGER': '/bin/cat', 07:19:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:31 INFO - 'PWD': '/builds/slave/test', 07:19:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:31 INFO - 'SHELL': '/bin/bash', 07:19:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.azen0aCO5y/Listeners', 07:19:31 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 07:19:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:31 INFO - 'USER': 'cltbld', 07:19:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:31 INFO - 'XPC_FLAGS': '0x0', 07:19:31 INFO - 'XPC_SERVICE_NAME': '0', 07:19:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:31 INFO - Downloading/unpacking flake8 07:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:19:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:19:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:19:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:19:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:19:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:19:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:19:34 INFO - no previously-included directories found matching 'docs/_build' 07:19:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:19:35 INFO - Running setup.py install for pep8 07:19:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:19:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:19:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:19:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:19:35 INFO - no previously-included directories found matching 'docs/_build' 07:19:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:19:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:19:35 INFO - Cleaning up... 07:19:35 INFO - Return code: 0 07:19:35 INFO - Running post-action listener: _resource_record_post_action 07:19:35 INFO - Running post-action listener: _start_resource_monitoring 07:19:35 INFO - Starting resource monitoring. 07:19:35 INFO - ##### 07:19:35 INFO - ##### Running install step. 07:19:35 INFO - ##### 07:19:35 INFO - Running pre-action listener: _resource_record_pre_action 07:19:35 INFO - Running main action method: install 07:19:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:19:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:19:35 INFO - Reading from file tmpfile_stdout 07:19:35 INFO - Detecting whether we're running mozinstall >=1.0... 07:19:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:19:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:19:35 INFO - Reading from file tmpfile_stdout 07:19:35 INFO - Output received: 07:19:35 INFO - Usage: mozinstall [options] installer 07:19:35 INFO - Options: 07:19:35 INFO - -h, --help show this help message and exit 07:19:35 INFO - -d DEST, --destination=DEST 07:19:35 INFO - Directory to install application into. [default: 07:19:35 INFO - "/builds/slave/test"] 07:19:35 INFO - --app=APP Application being installed. [default: firefox] 07:19:35 INFO - mkdir: /builds/slave/test/build/application 07:19:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:19:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 07:20:07 INFO - Reading from file tmpfile_stdout 07:20:07 INFO - Output received: 07:20:07 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:20:07 INFO - Running post-action listener: _resource_record_post_action 07:20:07 INFO - ##### 07:20:07 INFO - ##### Running run-tests step. 07:20:07 INFO - ##### 07:20:07 INFO - Running pre-action listener: _resource_record_pre_action 07:20:07 INFO - Running main action method: run_tests 07:20:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:20:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:20:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:20:07 INFO - 2016-04-30 07:20:07.777 screenresolution[1648:8294] starting screenresolution argv=screenresolution get 07:20:07 INFO - 2016-04-30 07:20:07.780 screenresolution[1648:8294] Display 0: 1600x1200x32@60 07:20:07 INFO - 2016-04-30 07:20:07.786 screenresolution[1649:8296] starting screenresolution argv=screenresolution list 07:20:07 INFO - Available Modes on Display 0 07:20:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:20:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:20:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:20:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:20:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:20:08 INFO - Intel Iris: 07:20:08 INFO - Chipset Model: Intel Iris 07:20:08 INFO - Type: GPU 07:20:08 INFO - Bus: Built-In 07:20:08 INFO - VRAM (Dynamic, Max): 1536 MB 07:20:08 INFO - Vendor: Intel (0x8086) 07:20:08 INFO - Device ID: 0x0a2e 07:20:08 INFO - Revision ID: 0x0009 07:20:08 INFO - Displays: 07:20:08 INFO - Display: 07:20:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:20:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:20:08 INFO - Display Serial Number: 1600x1200 60 07:20:08 INFO - Main Display: Yes 07:20:08 INFO - Mirror: Off 07:20:08 INFO - Online: Yes 07:20:08 INFO - Rotation: Supported 07:20:08 INFO - Return code: 0 07:20:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:20:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:20:08 INFO - Python 2.7.3 07:20:08 INFO - Return code: 0 07:20:08 INFO - grabbing minidump binary from tooltool 07:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:20:08 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dbe00>, '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 0x10b444db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:20:08 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 07:20:08 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 07:20:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:20:08 INFO - Return code: 0 07:20:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:20:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:20:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:20:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:20:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:20:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:20:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:20:09 INFO - Return code: 0 07:20:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0109', '--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 07:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0109 --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 07:20:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mb7JN4myA3/Render', 07:20:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:20:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:20:09 INFO - 'HOME': '/Users/cltbld', 07:20:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:20:09 INFO - 'LOGNAME': 'cltbld', 07:20:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:20:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:09 INFO - 'MOZ_NO_REMOTE': '1', 07:20:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:20:09 INFO - 'NO_EM_RESTART': '1', 07:20:09 INFO - 'PAGER': '/bin/cat', 07:20:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:09 INFO - 'PWD': '/builds/slave/test', 07:20:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:20:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:20:09 INFO - 'SHELL': '/bin/bash', 07:20:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.azen0aCO5y/Listeners', 07:20:09 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 07:20:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:20:09 INFO - 'USER': 'cltbld', 07:20:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:20:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:09 INFO - 'XPC_FLAGS': '0x0', 07:20:09 INFO - 'XPC_SERVICE_NAME': '0', 07:20:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:20:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0109', '--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 07:20:09 INFO - mozversion application_buildid: 20160430051339 07:20:09 INFO - mozversion application_changeset: 3f169c95e542f059b6b07d11e7f73533bdbf03d6 07:20:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:20:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:20:09 INFO - mozversion application_name: Firefox 07:20:09 INFO - mozversion application_remotingname: firefox-dev 07:20:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:20:09 INFO - mozversion application_vendor: Mozilla 07:20:09 INFO - mozversion application_version: 48.0a2 07:20:09 INFO - mozversion platform_buildid: 20160430051339 07:20:09 INFO - mozversion platform_changeset: 3f169c95e542f059b6b07d11e7f73533bdbf03d6 07:20:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:20:09 INFO - mozversion platform_version: 48.0a2 07:20:09 INFO - using testdate: 1462026009 07:20:09 INFO - actual date: 1462026009 07:20:09 INFO - starting webserver on 'localhost:49272' 07:20:09 INFO - SUITE-START | Running 2 tests 07:20:09 INFO - TEST-START | dromaeo_css 07:20:09 INFO - operating with platform_type : mac_ 07:20:09 INFO - Initialising browser for dromaeo_css test... 07:20:09 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmppTZRtG/profile http://localhost:49272/getInfo.html) 07:20:11 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 07:20:11 INFO - PROCESS | 1667 | colorDepth:24 07:20:11 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 07:20:11 INFO - PROCESS | 1667 | __metrics 07:20:11 INFO - PROCESS | 1667 | 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" 07:20:11 ERROR - PROCESS | 1667 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:20:12 INFO - TEST-INFO | 1667: exit 0 07:20:12 INFO - Browser initialized. 07:20:12 INFO - Running cycle 1/1 for dromaeo_css test... 07:20:12 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmppTZRtG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:20:24 INFO - PROCESS | 1670 | RSS: Main: 211582976 07:20:24 INFO - PROCESS | 1670 | 07:21:15 INFO - PROCESS | 1670 | 1462026075891 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] 07:23:26 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 07:23:27 INFO - PROCESS | 1670 | RSS: Main: 336654336 07:23:27 INFO - PROCESS | 1670 | 07:26:30 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 07:26:31 INFO - PROCESS | 1670 | RSS: Main: 360189952 07:26:31 INFO - PROCESS | 1670 | 07:29:33 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 07:29:33 INFO - PROCESS | 1670 | RSS: Main: 362795008 07:29:33 INFO - PROCESS | 1670 | 07:32:36 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 07:32:36 INFO - PROCESS | 1670 | RSS: Main: 359284736 07:32:36 INFO - PROCESS | 1670 | 07:35:40 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 07:35:40 INFO - PROCESS | 1670 | RSS: Main: 321482752 07:35:40 INFO - PROCESS | 1670 | 07:38:43 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 07:38:43 INFO - PROCESS | 1670 | RSS: Main: 370307072 07:38:43 INFO - PROCESS | 1670 | 07:38:43 INFO - PROCESS | 1670 | __start_tp_report 07:38:43 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 07:38:43 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 07:38:43 INFO - PROCESS | 1670 | |i|pagename|runs| 07:38:43 INFO - PROCESS | 1670 | |0;dojo.html;2889.8988535401263;3471.409860323746;3484.7734897231676;3498.7725797823155;3533.3110043541506;5701.743421546025;5710.314762228533;5710.628809127404;5720.771169153234;5722.513586345166;4898.412190537131;5540.778368865251;5708.914366284509;5750.827475175752;5755.568332375078;5081.466446023152;5141.357330333708;5147.150720131174;5175.792968281274;5212.635115541913;5214.270002199695;5222.660527065728;5234.6335756497065;5243.816466423676;5250.422453530108;5145.459726728695;5195.480451954812;5222.817201397952;5229.058769421503;5233.162693968966;5215.739213039352;5251.975864706384;5256.6057545684225;5258.50044245796;5298.523132918017;5049.166887463564;5099.413567439718;5100.642954993152;5159.380874295071;5175.120229560984;4862.927056094161;5600.859978500529;5601.103823388279;5605.775768969236;5620.988222120017;6194.256689197326;6254.530910181755;6254.562180647356;6277.497800176021;6284.654344011077;6115.266168059817;6283.842903927439;6284.905726414107;6325.468095620786;6336.873262534769;4832;5713.542916566665;5790.392008839095;5807.128930660435;5839.0949402842625;6268.874622507525;6283.622982621057;6286.465650419674;6292.685365731695;6303.653299068504;6010.158577799111;6212.192414986113;6213.285472170668;6214.130021796952;6227.719752611143;6134.620279381772;6177.536684748662;6292.685365731695;6294.339094395023;6295.559310848196;6185.041318595625;6240.095186198067;6256.718447669866;6271.905921411182;6284.308726040132;5143.331366922276;6229.719662615194;6271.811845644638;6302;6314.368563143741;4822.445418776815;4831.4685384607665;4934.703917764946;4970.3290055842;4989.301497790312;4419.11617676466;4432;4436.733795972252;4437.0460351024685;4466.972596302803;5588.44115588446;5677.14842773579;5686.0333743263745;5720.370759216483;5734.741936612862;5828.504577110908;6227.9377206227355;6253.280872699606;6297.559170857996;6302.810915672537;5701.173329867229;5701.429857014348;5715.657060576379;5717.113847353584;5724.4561766632105;5291.259223708684;5390.272313237741;5394.433584473652;5395.73021348931;5424.294841670637;5384.649997750055;6121.026213050743;6235.719392627343;6335.588186767845;6339.873202536016;6252.218472690914;6257.749690012349;6273.404026617464;6284.622922624657;6294.590851594632;6359.936400635935;6374.681265936777;6382.872342553216;6402.487800975818;6407.583507072025;6074.726637301421;6120.479759220516;6165.260168779596;6173.043178307369;6270.435660790551;6253.062040693932;6270.968645156745;6276.592021518587;6300.684965751786;6312.053192021233;5840.94159058421;6085.6348619083;6100.115483254992;6100.969495152496;6115.908261375994;5942.346341902474;6046.922842543216;6072.089186622042;6114.633122012693;6130.153553065376;5913.142594323885;5924.763009479572;5957.255343082114;5958.821235362946;5964.493018093337;5982.46157845796;6139.9079013813935;6143.293521245114;6143.938560614515;6152.661603611666;5888.646681199141;5934.139549765347;6006;6006.429389208019;6055.970485017469 07:38:43 INFO - PROCESS | 1670 | |1;ext.html;486.31672500137296;512.4728309637284;515.4973900447064;523.0506630465705;531.030868664687;30202.395952080973;31084;38137.427938580906;39478.60521394785;39502.80248598753;41500.37749433761;42063.57936420635;42202.155956880844;42205.577944220546;42306;9311.72064838056;9597;9599.520023998806;9606.759831004247;9615.375000624972;8405.495670259774;8628.09405012474;11473.770524589545;11564.363959982198;11578.24741391811;11471.885281147164;11594.362310072942;11631;11632.709182270426;11653.883461165407;8397.6221070052;11327.660170194908;11335.716607084807;11444.828327575051;11467.483963221675;9374.718758437259;11378.260413073123;11385.943070284678;11391.54433822646;11393.601223957205;38226;38745.031374215585;38777.22445551073;38790.22419551593;38862.80568597139;3218.5654936583496;3341.2649217172184;3348.983255083733;3479.634638362986;3728.459373390843;27994;28479;28498.715012849814;28777.712222877923;29196.70803291961;2737.54830452975;2772.916812495628;2773.459175460786;2776.4447110577967;2803.8457884816326;455388;456526;457206;457913;458627.70686146687;33804.49293260603;57867;58722.41277587255;58792.412075878696;58918.70540647269;35258.82370588182;35338.29323413517;35434.46848297278;35435.468467973;35483.82258088745;13256.93371533136;15264.23678816077;15326.386944522093;15621.218939053002;15653.217339133225;11853.762924741457;12026.879731202576;12037.699057523632;13219.867801322056;13277.601671949857;7514.0607424071995;7635.580043097571;7889.132195458495;8059.5164290142775;8070.515769053875;3291.489819078047;3404.1829960809227;3413.658634136616;3439.9828000860334;3443.8450269737423;986.6842610364758;1057.0222544146634;1073.7100982734773;1104.7909108553051;1122.8877112288706;28975;29626.111216663103;30122.945696900504;30222.697773021988;30313.696863031528;1018.8828284747201;1064.5209655654987;1093.1855767453299;1102.0522350778324;1110.4059328259343;15437;15437.536873893801;15545.922270388575;15608.687826243638;15639.609009774847;611412;620509;622023;622279;622357;57763.55591110256;59752.701236493536;59759.70120149459;59827.70086149628;59842.80314393688;1323.6426164935413;1343.6909510812568;1345.2063282663275;1491.5003473836168;1492.970140597182;22390;29098.7090129096;29148.854255728587;29469;30624.387512250076;53006.73496632492;53339;54736;54757.72621136869;54889;614972;617064;627316;629420;630464;25606.871965640054;31314;39269.64382137081;39469.60530394774;40051.59948400595;30257.546136808407;34503.96488105361;42503.7874810624;42805.57194428016;42811.7156485306;37140.07149821168;37394.43908341431;37508.812455938634;37823.24353512859;38795;37059.25881482409;37360;37469.62530374771;38016.429753553166;38167.04582385463;37164.88505344844;37248;37285.69500067593;37598.43602345912;37707.86876393713;30649.233769155948;32838.179045524055;32934.34131317408;32963.8351808249;33017.834910825295;24112.51774964526;24402.633960490963;24433.51132977366;24924.37689057788;25030.874845625654 07:38:43 INFO - PROCESS | 1670 | |2;jquery.html;394.98291898361714;400.075824844609;405.5073086200268;405.6774863983133;410.7740742591574;16848.663026739458;20416.89791551042;20664.690029649566;21164.682529762045;21793.801340926242;20941.790582094174;20947.68578471324;21440.03519841607;21496.785032149673;21507.03218355174;5091.745412729368;5431.995080910025;5436.728163591824;5437.211604317372;5441.646292990943;4717.504662010474;5331.973340133294;5332.280142180794;5336.946630533703;5401.918971215435;5262.973685131588;5406.378266499363;5414.512693857552;5436.3476382833915;5444.265024221719;5357.169638705968;5428.864278393052;5440.945590544084;5443.1835224716215;5451;5058.367704036996;5236.633435659505;5268.5521730652945;5270.077736396115;5280.07598670235;13321.000924930571;13874.167549946935;14048.367823447968;14070.155790652594;14126.92936535321;2094.350751267112;2318.26974503031;2344.1443872986406;2359.834811563191;2613.764761171504;10363.48182590875;11620.360880151587;11939.283642981449;14821.333040013225;15448.922755386151;1672.2474886301213;1753.7720096387516;1796.2455768577229;1829.0945981738992;1866.7759868815692;127493.72506274912;130205;134434.6556534432;136166.3191684045;136467;11867.228630139014;11875.821862672066;11903.821442678367;11936.28382297065;12087.214331068453;15629;15667;15694.372225111096;15720.92139539295;15740.055596664237;7282.70869165238;9034.593443294936;9100.362974591715;9109;9183.081691830763;8341.917088390222;8492.74521764348;9078.364514484056;9103.998560158378;9199.218066464291;3815.6947444204397;4665.86002419928;4693.296005599187;4701.341812146302;4765.547273009059;1803.431918945528;1839.9521053681954;1920.836728878033;1963.7054441833554;1976.1798853475934;841.8610929196614;905.8686490458847;978.1978777402604;1009.6516701738036;1067.8451624514403;20240.291589794582;21892;21903;21972.340829775134;21984.230551930606;907.2605826251583;945.7871978804714;967.5887747707337;969.8943204746595;985.5531367504537;9727.56225969833;10982.523263189985;11322.204755844965;11560.190786645022;11598.448341364887;585922;594559;635821;639851;640852;129307.70692293145;136398.31800840932;136599;136836;138946.30526847302;881.7354793561906;1032.0917592518686;1038.5274700011628;1055.3773273768513;1057.5981127171626;18216.36242731498;19016.809831901504;19095.04524773728;19201;19244.711329330352;49955.50044499509;51221;52308;53283;54373.72813135909;77187.228127718;77691;78077.219227807;78260;80560.79158812505;8229.806678031771;8298.70954516589;8427.452215605967;8510.106438824081;8708.129187081242;11056.778864422828;12613.684657883627;12682.239065656091;12991.28547929835;13038.543650972171;10758.354498730101;11109.722256943318;11143.832842507207;11170.553177872795;11196.720081998015;9641.807163856824;10893.237473376788;11149.442527873412;11210.103191744804;11227.494762735532;11279.436028198721;11334.716632084264;11417.828732568853;11638.185327027366;11715.121365897421;8300.2114799094;8314.210150035737;8381.37139714536;8385.916140838513;8441.15588441151;10446.373217606968;11078.390688512209;11285.7742845141;11372.658820235407;11372.97643212115 07:38:43 INFO - PROCESS | 1670 | |3;mootools.html;1417.18511855683;1582.493602047345;1623.0099639220077;1853.481025312912;1985.1067019841075;7797.53214807112;8598.05421403646;8799.120087991198;8807.86788198176;8817.603207855645;8410.579471026454;8631.784205394868;8633.69782057628;8643.481391116537;8719.389642725011;2149.1833103420677;2221.7889300516504;2223.2218723446827;2429.2226487524044;2472.431340791619;2226.1318085946496;2418.407490164912;2505.649209110726;2513.7611926866916;2516.3834860459124;2422.697162854643;2455.631655251718;2459.44662450948;2467.703875534938;2477.3682710908784;2136.572685462898;2433.987830060847;2437.2688193542044;2443.376938880583;2444.4499987502845;2389.7371289158177;2485.701715794098;2488.1789009626777;2495.9376015599573;2501.6122501012374;8617.956910215471;8687.131286871325;8696.478211307342;8705.651773929036;8708.17272359125;5227.477252274742;5370.462953704638;5385.623006389555;5420.457954204588;5437.374701909252;3391.4404123319623;3467.826608669556;3603;3613.5483064616924;3621.0947263184203;976.5898322704481;1032.339302846179;1041.3335465302212;1045.095991966943;1054.3357684658615;11735.178537502377;11756;11777.587784427504;11781.410929453494;11783.410829458579;4741.1465936131135;4789.485187466462;4883.536064073907;4895.681780684244;4921.237208232729;9958.751031224278;9996.600135994478;10006;10006.049425304584;10008.09927106564;5403.567714582824;5434.130539113723;5443.292371991695;5448.291722076105;5460.590455715837;4787.257975013878;4841.176999910023;4904.337914381513;4954.232094025432;4959.752012399438;4034.6772258219858;4059.208454351432;4088.1210539733506;4139.317012692933;4171.520275168334;1332.733453309342;1344.0793056756154;1344.5764584155959;1359.6274500995826;1365.2491129878526;876.6142897125244;901.9278457723366;924.9075092490697;959.1369761262625;961.427950369529;5862.941370586324;5921.703914804242;5933.495652869555;6044.1840351552855;6197.876042479215;447.7313611832939;462.8310666606664;471.516695387224;472.1100725133149;473.20973973464396;3878.864239751595;3951.940720889189;4004.599540045972;4048.473698419187;4108.137291168829;7119.537230080028;10705;10836.187285953427;10859.891401086046;10865.402402867918;10448.216383771096;10591.4704264788;10609.522571484302;10706.250562460553;10726.463676816127;7654.846903061907;8048.476849004796;8073.959630201812;8206.548639824747;8217.260446559838;4813.927791083207;4994.9750251248515;6030.7286172121485;6032.306284777307;6142.293636231891;9346.345755797029;9543;9826.75433114178;9953.701388958343;9966.601335946481;9047.909520904706;9905.603775848886;10054.64808731691;10071.69784906454;10072.446015469219;6970.128733908262;7326.413886889142;7594.392448604209;7604.087509498895;7605.809854753676;8026.277635012877;8086.3935204861045;8105.75682729519;8209.096999330188;8232.62953167097;7295.416366690546;7359.926400735924;7437.7396791114215;7449.404047676281;7539.585322807079;7177.1746249182015;7214.89177662346;7343.8898416522725;7407.370373518096;7416.406687465098;6941.514094013371;6996.895046574204;7043.542169758949;7045.330693584307;7051.1186101737285;4833.589144922721;4889.535494128052;4930.704157750546;5008.874778130576;5050.393952725696;4984.526469985346;5036.29491871131;5089.083964886356;5112.130937740668;5154.974225128851 07:38:43 INFO - PROCESS | 1670 | |4;prototype.html;265.8697238353207;303.65686773945436;323.103388098028;324.28656954699665;327.6314146585092;4091.7520156352302;5221.686698798075;5240.816571420001;5241.0828105081555;5312.707801070945;4517.344984977184;5051.520105589963;5253.921191182126;5262.578993680506;5275.70983595903;3351.497275408696;4005.698148101864;4054.4729185205883;4063.573324800898;4065.898352541188;3962.7584218558095;4043.272211002033;4067.430559721641;4102.323116685743;4108.77401742904;3840.4047372657283;4014.739041962263;4024.798760062;4060.1473690525027;4061.634452899253;3251.430999575065;4013.9397909031386;4026.2148880968225;4044.786564030808;4058.9852536865933;2735.8358498489956;3485.989063171677;3486.18074752433;3506.947915625328;3538.9646103538894;4395.978020109911;5043.798248070073;5100.540951314362;5113.897722045538;5138.820141295074;4024.315866302706;4604.342436722777;4622.445306563234;4630.560096790799;4642.9071418571775;4352.216601011816;4838.959623680926;5318.787248510055;5555.8888822223735;5560.636729390034;3588.3672928817327;3815.866444674451;3834.348160812641;3839.8272077756287;3860.9613903861164;5105.03004429161;5685;5806.680632565207;5871.970640146815;5893.999999999957;2981.7167369099902;3929.328056921264;3933.8033098344968;3940.3498422760495;3953.2686453006286;3234.272288735016;3781.9032480580872;3940.704447166474;3968.543617483969;3968.980155099206;4577.3362862384765;4580.358749775034;4592.609628181575;4598;4625.352450656911;4611.924633914685;4649.116667833101;5175.378954525406;5643.181738647873;5671.412004638708;1231.5320178332356;1246.569933372985;1257.4027337014845;1329.5147271245926;1388.9236092015037;910.4537277633499;935.396669148409;947.7772723409993;959.1751094059103;960.6973803251954;4648.814047438133;4659.2312268476;5542.3626282977975;5561.026820306431;5577.498025177753;4948.331975183376;5672.574556908248;5776.653400795965;6108.3280839107665;6184.536159787944;5010.599152067826;5290.523852853262;5520.282363292747;5531;5556.333100070044;3309.470484722433;3328.951380315193;3329.5505106810756;3342.983285083559;3349.6650334966307;6283.37166283368;6572.835679107965;6574.013897915255;6589.967050164814;6598.769043083469;6121.449069583669;6413.414408697803;6445.581037232565;6461;6482.35176482348;5476.315460567429;6559.704813283413;6578.967105164444;6582.3746744059235;6606.537542371988;5040.722760248222;5263.868403289948;5501.669899806025;5771.5671324649975;6304.558680892477;4812.171374877524;5739.799107031234;5756.30924289088;5769.249997500298;5774.220480235114;5049.434675250684;5155.767990440364;5362.410134885082;5730.627509211888;6134.38656134383;3731.5708693500596;3891.8637847675195;3909.941350879796;3921.745086569364;3956.4263181837896;4658.161530924466;4898.608111351012;5603.215549822947;5626.833669037532;5773.3072031356505;5524.806631767869;5530.585206109477;5576.4664717203095;5589.959664722428;5618.831435056954;4572.028181681933;4639.675222734376;5387.946120538744;5462.726863656881;5518.889622207453;4321.805518751601;5247.842564723064;5396.271503346998;5404.7297635118875;5499.285092938053;4868.634852386156;4936.629752768628;5041.899162016666;5474.562035037267;5564.805231816867;4813.4945830688675;5191.456186468527;5410.377806552139;5485.177223416519;5504.2018907257425 07:38:43 INFO - PROCESS | 1670 | |5;yui.html;655.6951017776735;675.5878913862543;689.8516818883938;733.1129333506453;734.6179986407066;14359.92820035901;14410.711785764279;14872.47946321879;15515.767263491056;15525.301361438735;14218.07582507139;14232.217228052454;14305.284735763196;14316.35576399062;14341.928290358534;741.0995640297051;762.9814198045616;886.5434301334818;928.6981730937472;933.551895090357;771.5756334016297;929.0941332201083;934.2525979216633;934.3739694404765;937.4890684576887;774.7017398301638;981.8281800684887;991.0634450444333;992.5781542844298;996.4718699089494;943.273679266964;979.1139019187611;984.1732944326728;984.5618699678646;986.758244230161;721.4228617106294;725.7713820146689;727.5125665803924;728.6611725547646;728.7595093619093;15176.317065732068;15236.847631523653;15245.771313430307;15247.23763811816;15265.23673816316;12574.308413037279;12723.491060357575;12945.352732363439;13020.414081366362;13048.956083513296;9935.701928942144;9995.400275983464;10030.699079027641;10308.123809476203;10690.358578485315;3434.9656503434894;3439.2777516721517;3525.806080665562;3531.3996620574308;3604.477350784148;71945.84162475081;78202.6089869547;78863;81046;81397;9631.470269135265;9739.56171972263;9792.314537982273;9832;9992.400455972665;21830.126794928343;22063.33809985703;22101.889490552767;22107.226247081275;22356.105755769586;7596.191004667092;7673.654685539164;7740.922590774133;8041.236082572146;8043.19568043191;4029.7985100744845;4153.91692166155;4156.625903668684;4159.667226621863;4221.113566151143;3582.140286331262;3614.313280476701;3652.3060618482937;3654.250878569887;3657;569.4504802865255;571.9656820590694;649.7790751144634;658.8385845467891;662.4700239808134;523.2857149990311;595.9046552551657;597.9222701048923;602.764921680545;626.4424662050778;7979.441439099323;8276.08963014068;8386;8448.619812108469;8471.152884711479;576.6165499942491;588.6997631208029;592.8331946734692;603.2820943077724;614.7203022624695;3170.4134437437983;3206.9839650802064;3212.229065024424;3253.6420993690676;3256.446404111307;96111.07777844404;96309;96318;100822.98354032879;108541;77243;77688.22311776923;78215;78454.82317765238;78542.42915141779;45901.770491147334;68990.65504672444;70681;71414.9287760684;71723.64138179275;8137.918620813716;8155.388345873965;8233.46482478637;8329.875051874105;8357.582120893809;42114.789426052674;42228.36657450202;43588;44089.77955110204;44399.22403103969;56985.715071424376;57150;60356.39643603683;63641;63728;10326.01902819231;10429.061384475433;10837.349759014485;11285.097192224439;11562.248453850472;11849;11925.582604608797;12119.697007574881;12165.817512737493;12219.816702749644;11232.943835280772;11278;11335.433228338716;11643.5342586299;11711.824322634997;10766.515506802056;10993.395363255098;11110.833337499782;11320.037796787365;11960.76078478443;8939.284857211538;10629.681109566725;11035.282706624044;11046.502907369028;11103.611373601885;9181.954090229507;9637.22902167839;9680.031996800546;9703.951480242553;9934.900650993397;9452.669156579486;9554.28342874301;9613.615455381707;9637.37357071788;9653.324267301221 07:38:43 INFO - PROCESS | 1670 | __end_tp_report 07:38:43 INFO - PROCESS | 1670 | __start_cc_report 07:38:43 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1216 07:38:43 INFO - PROCESS | 1670 | __end_cc_report 07:38:43 INFO - PROCESS | 1670 | __startTimestamp1462027123604__endTimestamp 07:38:43 INFO - PROCESS | 1670 | 07:38:43 INFO - PROCESS | 1670 | ------- Summary: start ------- 07:38:43 INFO - PROCESS | 1670 | Number of tests: 6 07:38:43 INFO - PROCESS | 1670 | 07:38:43 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5721.00 Median:5934.14 stddev:656.68 (11.1%) stddev-sans-first:620.00 07:38:43 INFO - PROCESS | 1670 | Values: 2889.9 3471.4 3484.8 3498.8 3533.3 5701.7 5710.3 5710.6 5720.8 5722.5 4898.4 5540.8 5708.9 5750.8 5755.6 5081.5 5141.4 5147.2 5175.8 5212.6 5214.3 5222.7 5234.6 5243.8 5250.4 5145.5 5195.5 5222.8 5229.1 5233.2 5215.7 5252.0 5256.6 5258.5 5298.5 5049.2 5099.4 5100.6 5159.4 5175.1 4862.9 5600.9 5601.1 5605.8 5621.0 6194.3 6254.5 6254.6 6277.5 6284.7 6115.3 6283.8 6284.9 6325.5 6336.9 4832.0 5713.5 5790.4 5807.1 5839.1 6268.9 6283.6 6286.5 6292.7 6303.7 6010.2 6212.2 6213.3 6214.1 6227.7 6134.6 6177.5 6292.7 6294.3 6295.6 6185.0 6240.1 6256.7 6271.9 6284.3 5143.3 6229.7 6271.8 6302.0 6314.4 4822.4 4831.5 4934.7 4970.3 4989.3 4419.1 4432.0 4436.7 4437.0 4467.0 5588.4 5677.1 5686.0 5720.4 5734.7 5828.5 6227.9 6253.3 6297.6 6302.8 5701.2 5701.4 5715.7 5717.1 5724.5 5291.3 5390.3 5394.4 5395.7 5424.3 5384.6 6121.0 6235.7 6335.6 6339.9 6252.2 6257.7 6273.4 6284.6 6294.6 6359.9 6374.7 6382.9 6402.5 6407.6 6074.7 6120.5 6165.3 6173.0 6270.4 6253.1 6271.0 6276.6 6300.7 6312.1 5840.9 6085.6 6100.1 6101.0 6115.9 5942.3 6046.9 6072.1 6114.6 6130.2 5913.1 5924.8 5957.3 5958.8 5964.5 5982.5 6139.9 6143.3 6143.9 6152.7 5888.6 5934.1 6006.0 6006.4 6056.0 07:38:43 INFO - PROCESS | 1670 | 07:38:43 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:68543.22 Median:19369.23 stddev:153256.81 (791.2%) stddev-sans-first:153601.55 07:38:43 INFO - PROCESS | 1670 | Values: 486.3 512.5 515.5 523.1 531.0 30202.4 31084.0 38137.4 39478.6 39502.8 41500.4 42063.6 42202.2 42205.6 42306.0 9311.7 9597.0 9599.5 9606.8 9615.4 8405.5 8628.1 11473.8 11564.4 11578.2 11471.9 11594.4 11631.0 11632.7 11653.9 8397.6 11327.7 11335.7 11444.8 11467.5 9374.7 11378.3 11385.9 11391.5 11393.6 38226.0 38745.0 38777.2 38790.2 38862.8 3218.6 3341.3 3349.0 3479.6 3728.5 27994.0 28479.0 28498.7 28777.7 29196.7 2737.5 2772.9 2773.5 2776.4 2803.8 455388.0 456526.0 457206.0 457913.0 458627.7 33804.5 57867.0 58722.4 58792.4 58918.7 35258.8 35338.3 35434.5 35435.5 35483.8 13256.9 15264.2 15326.4 15621.2 15653.2 11853.8 12026.9 12037.7 13219.9 13277.6 7514.1 7635.6 7889.1 8059.5 8070.5 3291.5 3404.2 3413.7 3440.0 3443.8 986.7 1057.0 1073.7 1104.8 1122.9 28975.0 29626.1 30122.9 30222.7 30313.7 1018.9 1064.5 1093.2 1102.1 1110.4 15437.0 15437.5 15545.9 15608.7 15639.6 611412.0 620509.0 622023.0 622279.0 622357.0 57763.6 59752.7 59759.7 59827.7 59842.8 1323.6 1343.7 1345.2 1491.5 1493.0 22390.0 29098.7 29148.9 29469.0 30624.4 53006.7 53339.0 54736.0 54757.7 54889.0 614972.0 617064.0 627316.0 629420.0 630464.0 25606.9 31314.0 39269.6 39469.6 40051.6 30257.5 34504.0 42503.8 42805.6 42811.7 37140.1 37394.4 37508.8 37823.2 38795.0 37059.3 37360.0 37469.6 38016.4 38167.0 37164.9 37248.0 37285.7 37598.4 37707.9 30649.2 32838.2 32934.3 32963.8 33017.8 24112.5 24402.6 24433.5 24924.4 25030.9 07:38:43 INFO - PROCESS | 1670 | 07:38:43 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:36050.42 Median:21842.90 stddev:103850.26 (475.4%) stddev-sans-first:104107.08 07:38:43 INFO - PROCESS | 1670 | Values: 395.0 400.1 405.5 405.7 410.8 16848.7 20416.9 20664.7 21164.7 21793.8 20941.8 20947.7 21440.0 21496.8 21507.0 5091.7 5432.0 5436.7 5437.2 5441.6 4717.5 5332.0 5332.3 5336.9 5401.9 5263.0 5406.4 5414.5 5436.3 5444.3 5357.2 5428.9 5440.9 5443.2 5451.0 5058.4 5236.6 5268.6 5270.1 5280.1 13321.0 13874.2 14048.4 14070.2 14126.9 2094.4 2318.3 2344.1 2359.8 2613.8 10363.5 11620.4 11939.3 14821.3 15448.9 1672.2 1753.8 1796.2 1829.1 1866.8 127493.7 130205.0 134434.7 136166.3 136467.0 11867.2 11875.8 11903.8 11936.3 12087.2 15629.0 15667.0 15694.4 15720.9 15740.1 7282.7 9034.6 9100.4 9109.0 9183.1 8341.9 8492.7 9078.4 9104.0 9199.2 3815.7 4665.9 4693.3 4701.3 4765.5 1803.4 1840.0 1920.8 1963.7 1976.2 841.9 905.9 978.2 1009.7 1067.8 20240.3 21892.0 21903.0 21972.3 21984.2 907.3 945.8 967.6 969.9 985.6 9727.6 10982.5 11322.2 11560.2 11598.4 585922.0 594559.0 635821.0 639851.0 640852.0 129307.7 136398.3 136599.0 136836.0 138946.3 881.7 1032.1 1038.5 1055.4 1057.6 18216.4 19016.8 19095.0 19201.0 19244.7 49955.5 51221.0 52308.0 53283.0 54373.7 77187.2 77691.0 78077.2 78260.0 80560.8 8229.8 8298.7 8427.5 8510.1 8708.1 11056.8 12613.7 12682.2 12991.3 13038.5 10758.4 11109.7 11143.8 11170.6 11196.7 9641.8 10893.2 11149.4 11210.1 11227.5 11279.4 11334.7 11417.8 11638.2 11715.1 8300.2 8314.2 8381.4 8385.9 8441.2 10446.4 11078.4 11285.8 11372.7 11373.0 07:38:43 INFO - PROCESS | 1670 | 07:38:43 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5680.57 Median:4942.47 stddev:3154.13 (63.8%) stddev-sans-first:3146.70 07:38:43 INFO - PROCESS | 1670 | Values: 1417.2 1582.5 1623.0 1853.5 1985.1 7797.5 8598.1 8799.1 8807.9 8817.6 8410.6 8631.8 8633.7 8643.5 8719.4 2149.2 2221.8 2223.2 2429.2 2472.4 2226.1 2418.4 2505.6 2513.8 2516.4 2422.7 2455.6 2459.4 2467.7 2477.4 2136.6 2434.0 2437.3 2443.4 2444.4 2389.7 2485.7 2488.2 2495.9 2501.6 8618.0 8687.1 8696.5 8705.7 8708.2 5227.5 5370.5 5385.6 5420.5 5437.4 3391.4 3467.8 3603.0 3613.5 3621.1 976.6 1032.3 1041.3 1045.1 1054.3 11735.2 11756.0 11777.6 11781.4 11783.4 4741.1 4789.5 4883.5 4895.7 4921.2 9958.8 9996.6 10006.0 10006.0 10008.1 5403.6 5434.1 5443.3 5448.3 5460.6 4787.3 4841.2 4904.3 4954.2 4959.8 4034.7 4059.2 4088.1 4139.3 4171.5 1332.7 1344.1 1344.6 1359.6 1365.2 876.6 901.9 924.9 959.1 961.4 5862.9 5921.7 5933.5 6044.2 6197.9 447.7 462.8 471.5 472.1 473.2 3878.9 3951.9 4004.6 4048.5 4108.1 7119.5 10705.0 10836.2 10859.9 10865.4 10448.2 10591.5 10609.5 10706.3 10726.5 7654.8 8048.5 8074.0 8206.5 8217.3 4813.9 4995.0 6030.7 6032.3 6142.3 9346.3 9543.0 9826.8 9953.7 9966.6 9047.9 9905.6 10054.6 10071.7 10072.4 6970.1 7326.4 7594.4 7604.1 7605.8 8026.3 8086.4 8105.8 8209.1 8232.6 7295.4 7359.9 7437.7 7449.4 7539.6 7177.2 7214.9 7343.9 7407.4 7416.4 6941.5 6996.9 7043.5 7045.3 7051.1 4833.6 4889.5 4930.7 5008.9 5050.4 4984.5 5036.3 5089.1 5112.1 5155.0 07:38:43 INFO - PROCESS | 1670 | 07:38:43 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:4561.55 Median:5041.31 stddev:1432.58 (28.4%) stddev-sans-first:1399.84 07:38:43 INFO - PROCESS | 1670 | Values: 265.9 303.7 323.1 324.3 327.6 4091.8 5221.7 5240.8 5241.1 5312.7 4517.3 5051.5 5253.9 5262.6 5275.7 3351.5 4005.7 4054.5 4063.6 4065.9 3962.8 4043.3 4067.4 4102.3 4108.8 3840.4 4014.7 4024.8 4060.1 4061.6 3251.4 4013.9 4026.2 4044.8 4059.0 2735.8 3486.0 3486.2 3506.9 3539.0 4396.0 5043.8 5100.5 5113.9 5138.8 4024.3 4604.3 4622.4 4630.6 4642.9 4352.2 4839.0 5318.8 5555.9 5560.6 3588.4 3815.9 3834.3 3839.8 3861.0 5105.0 5685.0 5806.7 5872.0 5894.0 2981.7 3929.3 3933.8 3940.3 3953.3 3234.3 3781.9 3940.7 3968.5 3969.0 4577.3 4580.4 4592.6 4598.0 4625.4 4611.9 4649.1 5175.4 5643.2 5671.4 1231.5 1246.6 1257.4 1329.5 1388.9 910.5 935.4 947.8 959.2 960.7 4648.8 4659.2 5542.4 5561.0 5577.5 4948.3 5672.6 5776.7 6108.3 6184.5 5010.6 5290.5 5520.3 5531.0 5556.3 3309.5 3329.0 3329.6 3343.0 3349.7 6283.4 6572.8 6574.0 6590.0 6598.8 6121.4 6413.4 6445.6 6461.0 6482.4 5476.3 6559.7 6579.0 6582.4 6606.5 5040.7 5263.9 5501.7 5771.6 6304.6 4812.2 5739.8 5756.3 5769.2 5774.2 5049.4 5155.8 5362.4 5730.6 6134.4 3731.6 3891.9 3909.9 3921.7 3956.4 4658.2 4898.6 5603.2 5626.8 5773.3 5524.8 5530.6 5576.5 5590.0 5618.8 4572.0 4639.7 5387.9 5462.7 5518.9 4321.8 5247.8 5396.3 5404.7 5499.3 4868.6 4936.6 5041.9 5474.6 5564.8 4813.5 5191.5 5410.4 5485.2 5504.2 07:38:43 INFO - PROCESS | 1670 | 07:38:43 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:17941.53 Median:603.02 stddev:25415.93 (4214.7%) stddev-sans-first:25454.08 07:38:43 INFO - PROCESS | 1670 | Values: 655.7 675.6 689.9 733.1 734.6 14359.9 14410.7 14872.5 15515.8 15525.3 14218.1 14232.2 14305.3 14316.4 14341.9 741.1 763.0 886.5 928.7 933.6 771.6 929.1 934.3 934.4 937.5 774.7 981.8 991.1 992.6 996.5 943.3 979.1 984.2 984.6 986.8 721.4 725.8 727.5 728.7 728.8 15176.3 15236.8 15245.8 15247.2 15265.2 12574.3 12723.5 12945.4 13020.4 13049.0 9935.7 9995.4 10030.7 10308.1 10690.4 3435.0 3439.3 3525.8 3531.4 3604.5 71945.8 78202.6 78863.0 81046.0 81397.0 9631.5 9739.6 9792.3 9832.0 9992.4 21830.1 22063.3 22101.9 22107.2 22356.1 7596.2 7673.7 7740.9 8041.2 8043.2 4029.8 4153.9 4156.6 4159.7 4221.1 3582.1 3614.3 3652.3 3654.3 3657.0 569.5 572.0 649.8 658.8 662.5 523.3 595.9 597.9 602.8 626.4 7979.4 8276.1 8386.0 8448.6 8471.2 576.6 588.7 592.8 603.3 614.7 3170.4 3207.0 3212.2 3253.6 3256.4 96111.1 96309.0 96318.0 100823.0 108541.0 77243.0 77688.2 78215.0 78454.8 78542.4 45901.8 68990.7 70681.0 71414.9 71723.6 8137.9 8155.4 8233.5 8329.9 8357.6 42114.8 42228.4 43588.0 44089.8 44399.2 56985.7 57150.0 60356.4 63641.0 63728.0 10326.0 10429.1 10837.3 11285.1 11562.2 11849.0 11925.6 12119.7 12165.8 12219.8 11232.9 11278.0 11335.4 11643.5 11711.8 10766.5 10993.4 11110.8 11320.0 11960.8 8939.3 10629.7 11035.3 11046.5 11103.6 9182.0 9637.2 9680.0 9704.0 9934.9 9452.7 9554.3 9613.6 9637.4 9653.3 07:38:43 INFO - PROCESS | 1670 | -------- Summary: end -------- 07:38:43 INFO - PROCESS | 1670 | 07:38:44 INFO - TEST-INFO | 1670: exit 0 07:38:44 INFO - TEST-OK | dromaeo_css | took 1114868ms 07:38:44 INFO - TEST-START | kraken 07:38:44 INFO - operating with platform_type : mac_ 07:38:44 INFO - Initialising browser for kraken test... 07:38:44 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpDU8Bo8/profile http://localhost:49272/getInfo.html) 07:38:46 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 07:38:46 INFO - PROCESS | 1677 | colorDepth:24 07:38:46 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 07:38:46 INFO - PROCESS | 1677 | __metrics 07:38:46 INFO - PROCESS | 1677 | 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" 07:38:46 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:38:47 INFO - TEST-INFO | 1677: exit 0 07:38:47 INFO - Browser initialized. 07:38:47 INFO - Running cycle 1/1 for kraken test... 07:38:47 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpDU8Bo8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:38:59 INFO - PROCESS | 1679 | RSS: Main: 211705856 07:38:59 INFO - PROCESS | 1679 | 07:39:02 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 07:39:03 INFO - PROCESS | 1679 | RSS: Main: 269987840 07:39:03 INFO - PROCESS | 1679 | 07:39:05 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 07:39:06 INFO - PROCESS | 1679 | RSS: Main: 284782592 07:39:06 INFO - PROCESS | 1679 | 07:39:09 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 07:39:10 INFO - PROCESS | 1679 | RSS: Main: 375062528 07:39:10 INFO - PROCESS | 1679 | 07:39:12 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 07:39:12 INFO - PROCESS | 1679 | RSS: Main: 278863872 07:39:12 INFO - PROCESS | 1679 | 07:39:14 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:39:15 INFO - PROCESS | 1679 | RSS: Main: 278323200 07:39:15 INFO - PROCESS | 1679 | 07:39:18 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 07:39:18 INFO - PROCESS | 1679 | RSS: Main: 316403712 07:39:18 INFO - PROCESS | 1679 | 07:39:21 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 07:39:22 INFO - PROCESS | 1679 | RSS: Main: 317489152 07:39:22 INFO - PROCESS | 1679 | 07:39:25 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 07:39:25 INFO - PROCESS | 1679 | RSS: Main: 316362752 07:39:25 INFO - PROCESS | 1679 | 07:39:27 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:39:28 INFO - PROCESS | 1679 | RSS: Main: 307863552 07:39:28 INFO - PROCESS | 1679 | 07:39:30 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:39:30 INFO - PROCESS | 1679 | RSS: Main: 314552320 07:39:30 INFO - PROCESS | 1679 | 07:39:32 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:39:33 INFO - PROCESS | 1679 | RSS: Main: 317222912 07:39:33 INFO - PROCESS | 1679 | 07:39:36 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:39:37 INFO - PROCESS | 1679 | RSS: Main: 322965504 07:39:37 INFO - PROCESS | 1679 | 07:39:40 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:39:40 INFO - PROCESS | 1679 | RSS: Main: 320577536 07:39:40 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 07:39:42 INFO - PROCESS | 1679 | RSS: Main: 384802816 07:39:42 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | __start_tp_report 07:39:42 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 07:39:42 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 07:39:42 INFO - PROCESS | 1679 | |i|pagename|runs| 07:39:42 INFO - PROCESS | 1679 | |0;ai-astar;114;115;107;115;114;116;122;166;140;114 07:39:42 INFO - PROCESS | 1679 | |1;audio-beat-detection;151;121;161;157;121;139;121;154;156;119 07:39:42 INFO - PROCESS | 1679 | |2;audio-dft;186;184;185;190;186;185;185;188;182;186 07:39:42 INFO - PROCESS | 1679 | |3;audio-fft;72;77;73;153;57;55;61;66;141;60 07:39:42 INFO - PROCESS | 1679 | |4;audio-oscillator;90;89;138;77;84;94;125;79;77;139 07:39:42 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;87;122;89;87;89;121;90;87;88;85 07:39:42 INFO - PROCESS | 1679 | |6;imaging-darkroom;94;94;97;141;150;113;96;94;100;94 07:39:42 INFO - PROCESS | 1679 | |7;imaging-desaturate;86;95;86;83;84;86;85;86;86;85 07:39:42 INFO - PROCESS | 1679 | |8;json-parse-financial;62;64;66;64;63;63;65;83;60;55 07:39:42 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;62;65;72;74;75;68;68;70;67;68 07:39:42 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;82;82;82;82;83;93;105;106;82;83 07:39:42 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;140;156;162;151;136;149;153;149;185;139 07:39:42 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;170;170;168;169;169;168;170;167;168;170 07:39:42 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;71;68;69;69;68;68;69;70;68;69 07:39:42 INFO - PROCESS | 1679 | __end_tp_report 07:39:42 INFO - PROCESS | 1679 | __start_cc_report 07:39:42 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,2028 07:39:42 INFO - PROCESS | 1679 | __end_cc_report 07:39:42 INFO - PROCESS | 1679 | __startTimestamp1462027182606__endTimestamp 07:39:42 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | ------- Summary: start ------- 07:39:42 INFO - PROCESS | 1679 | Number of tests: 14 07:39:42 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:122.30 Median:115.50 stddev:17.67 (15.3%) stddev-sans-first:18.48 07:39:42 INFO - PROCESS | 1679 | Values: 114.0 115.0 107.0 115.0 114.0 116.0 122.0 166.0 140.0 114.0 07:39:42 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:140.00 Median:152.50 stddev:17.73 (11.6%) stddev-sans-first:18.35 07:39:42 INFO - PROCESS | 1679 | Values: 151.0 121.0 161.0 157.0 121.0 139.0 121.0 154.0 156.0 119.0 07:39:42 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:185.70 Median:186.00 stddev:2.16 (1.2%) stddev-sans-first:2.29 07:39:42 INFO - PROCESS | 1679 | Values: 186.0 184.0 185.0 190.0 186.0 185.0 185.0 188.0 182.0 186.0 07:39:42 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:81.50 Median:63.50 stddev:35.37 (55.7%) stddev-sans-first:37.35 07:39:42 INFO - PROCESS | 1679 | Values: 72.0 77.0 73.0 153.0 57.0 55.0 61.0 66.0 141.0 60.0 07:39:42 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:99.20 Median:81.50 stddev:24.93 (30.6%) stddev-sans-first:26.22 07:39:42 INFO - PROCESS | 1679 | Values: 90.0 89.0 138.0 77.0 84.0 94.0 125.0 79.0 77.0 139.0 07:39:42 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:94.50 Median:87.50 stddev:14.30 (16.3%) stddev-sans-first:14.91 07:39:42 INFO - PROCESS | 1679 | Values: 87.0 122.0 89.0 87.0 89.0 121.0 90.0 87.0 88.0 85.0 07:39:42 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:107.30 Median:94.00 stddev:21.05 (22.4%) stddev-sans-first:21.76 07:39:42 INFO - PROCESS | 1679 | Values: 94.0 94.0 97.0 141.0 150.0 113.0 96.0 94.0 100.0 94.0 07:39:42 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:86.20 Median:86.00 stddev:3.26 (3.8%) stddev-sans-first:3.46 07:39:42 INFO - PROCESS | 1679 | Values: 86.0 95.0 86.0 83.0 84.0 86.0 85.0 86.0 86.0 85.0 07:39:42 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:64.00 stddev:7.20 (11.2%) stddev-sans-first:7.58 07:39:42 INFO - PROCESS | 1679 | Values: 62.0 64.0 66.0 64.0 63.0 63.0 65.0 83.0 60.0 55.0 07:39:42 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:69.00 stddev:3.98 (5.8%) stddev-sans-first:3.35 07:39:42 INFO - PROCESS | 1679 | Values: 62.0 65.0 72.0 74.0 75.0 68.0 68.0 70.0 67.0 68.0 07:39:42 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:82.00 stddev:9.82 (12.0%) stddev-sans-first:10.17 07:39:42 INFO - PROCESS | 1679 | Values: 82.0 82.0 82.0 82.0 83.0 93.0 105.0 106.0 82.0 83.0 07:39:42 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:152.00 Median:152.00 stddev:14.12 (9.3%) stddev-sans-first:14.29 07:39:42 INFO - PROCESS | 1679 | Values: 140.0 156.0 162.0 151.0 136.0 149.0 153.0 149.0 185.0 139.0 07:39:42 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.90 Median:169.50 stddev:1.10 (0.6%) stddev-sans-first:1.09 07:39:42 INFO - PROCESS | 1679 | Values: 170.0 170.0 168.0 169.0 169.0 168.0 170.0 167.0 168.0 170.0 07:39:42 INFO - PROCESS | 1679 | 07:39:42 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:0.99 (1.4%) stddev-sans-first:0.71 07:39:42 INFO - PROCESS | 1679 | Values: 71.0 68.0 69.0 69.0 68.0 68.0 69.0 70.0 68.0 69.0 07:39:42 INFO - PROCESS | 1679 | -------- Summary: end -------- 07:39:42 INFO - PROCESS | 1679 | 07:39:43 INFO - TEST-INFO | 1679: exit 0 07:39:43 INFO - TEST-OK | kraken | took 59099ms 07:39:43 INFO - SUITE-END | took 1173s 07:39:43 INFO - Completed test suite (00:19:34) 07:39:43 INFO - javascript benchmark 07:39:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2889.8988535401263, 3471.409860323746, 3484.7734897231676, 3498.7725797823155, 3533.3110043541506, 5701.743421546025, 5710.314762228533, 5710.628809127404, 5720.771169153234, 5722.513586345166, 4898.412190537131, 5540.778368865251, 5708.914366284509, 5750.827475175752, 5755.568332375078, 5081.466446023152, 5141.357330333708, 5147.150720131174, 5175.792968281274, 5212.635115541913, 5214.270002199695, 5222.660527065728, 5234.6335756497065, 5243.816466423676, 5250.422453530108, 5145.459726728695, 5195.480451954812, 5222.817201397952, 5229.058769421503, 5233.162693968966, 5215.739213039352, 5251.975864706384, 5256.6057545684225, 5258.50044245796, 5298.523132918017, 5049.166887463564, 5099.413567439718, 5100.642954993152, 5159.380874295071, 5175.120229560984, 4862.927056094161, 5600.859978500529, 5601.103823388279, 5605.775768969236, 5620.988222120017, 6194.256689197326, 6254.530910181755, 6254.562180647356, 6277.497800176021, 6284.654344011077, 6115.266168059817, 6283.842903927439, 6284.905726414107, 6325.468095620786, 6336.873262534769, 4832.0, 5713.542916566665, 5790.392008839095, 5807.128930660435, 5839.0949402842625, 6268.874622507525, 6283.622982621057, 6286.465650419674, 6292.685365731695, 6303.653299068504, 6010.158577799111, 6212.192414986113, 6213.285472170668, 6214.130021796952, 6227.719752611143, 6134.620279381772, 6177.536684748662, 6292.685365731695, 6294.339094395023, 6295.559310848196, 6185.041318595625, 6240.095186198067, 6256.718447669866, 6271.905921411182, 6284.308726040132, 5143.331366922276, 6229.719662615194, 6271.811845644638, 6302.0, 6314.368563143741, 4822.445418776815, 4831.4685384607665, 4934.703917764946, 4970.3290055842, 4989.301497790312, 4419.11617676466, 4432.0, 4436.733795972252, 4437.0460351024685, 4466.972596302803, 5588.44115588446, 5677.14842773579, 5686.0333743263745, 5720.370759216483, 5734.741936612862, 5828.504577110908, 6227.9377206227355, 6253.280872699606, 6297.559170857996, 6302.810915672537, 5701.173329867229, 5701.429857014348, 5715.657060576379, 5717.113847353584, 5724.4561766632105, 5291.259223708684, 5390.272313237741, 5394.433584473652, 5395.73021348931, 5424.294841670637, 5384.649997750055, 6121.026213050743, 6235.719392627343, 6335.588186767845, 6339.873202536016, 6252.218472690914, 6257.749690012349, 6273.404026617464, 6284.622922624657, 6294.590851594632, 6359.936400635935, 6374.681265936777, 6382.872342553216, 6402.487800975818, 6407.583507072025, 6074.726637301421, 6120.479759220516, 6165.260168779596, 6173.043178307369, 6270.435660790551, 6253.062040693932, 6270.968645156745, 6276.592021518587, 6300.684965751786, 6312.053192021233, 5840.94159058421, 6085.6348619083, 6100.115483254992, 6100.969495152496, 6115.908261375994, 5942.346341902474, 6046.922842543216, 6072.089186622042, 6114.633122012693, 6130.153553065376, 5913.142594323885, 5924.763009479572, 5957.255343082114, 5958.821235362946, 5964.493018093337, 5982.46157845796, 6139.9079013813935, 6143.293521245114, 6143.938560614515, 6152.661603611666, 5888.646681199141, 5934.139549765347, 6006.0, 6006.429389208019, 6055.970485017469], "value": 5679.411392364373, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.31672500137296, 512.4728309637284, 515.4973900447064, 523.0506630465705, 531.030868664687, 30202.395952080973, 31084.0, 38137.427938580906, 39478.60521394785, 39502.80248598753, 41500.37749433761, 42063.57936420635, 42202.155956880844, 42205.577944220546, 42306.0, 9311.72064838056, 9597.0, 9599.520023998806, 9606.759831004247, 9615.375000624972, 8405.495670259774, 8628.09405012474, 11473.770524589545, 11564.363959982198, 11578.24741391811, 11471.885281147164, 11594.362310072942, 11631.0, 11632.709182270426, 11653.883461165407, 8397.6221070052, 11327.660170194908, 11335.716607084807, 11444.828327575051, 11467.483963221675, 9374.718758437259, 11378.260413073123, 11385.943070284678, 11391.54433822646, 11393.601223957205, 38226.0, 38745.031374215585, 38777.22445551073, 38790.22419551593, 38862.80568597139, 3218.5654936583496, 3341.2649217172184, 3348.983255083733, 3479.634638362986, 3728.459373390843, 27994.0, 28479.0, 28498.715012849814, 28777.712222877923, 29196.70803291961, 2737.54830452975, 2772.916812495628, 2773.459175460786, 2776.4447110577967, 2803.8457884816326, 455388.0, 456526.0, 457206.0, 457913.0, 458627.70686146687, 33804.49293260603, 57867.0, 58722.41277587255, 58792.412075878696, 58918.70540647269, 35258.82370588182, 35338.29323413517, 35434.46848297278, 35435.468467973, 35483.82258088745, 13256.93371533136, 15264.23678816077, 15326.386944522093, 15621.218939053002, 15653.217339133225, 11853.762924741457, 12026.879731202576, 12037.699057523632, 13219.867801322056, 13277.601671949857, 7514.0607424071995, 7635.580043097571, 7889.132195458495, 8059.5164290142775, 8070.515769053875, 3291.489819078047, 3404.1829960809227, 3413.658634136616, 3439.9828000860334, 3443.8450269737423, 986.6842610364758, 1057.0222544146634, 1073.7100982734773, 1104.7909108553051, 1122.8877112288706, 28975.0, 29626.111216663103, 30122.945696900504, 30222.697773021988, 30313.696863031528, 1018.8828284747201, 1064.5209655654987, 1093.1855767453299, 1102.0522350778324, 1110.4059328259343, 15437.0, 15437.536873893801, 15545.922270388575, 15608.687826243638, 15639.609009774847, 611412.0, 620509.0, 622023.0, 622279.0, 622357.0, 57763.55591110256, 59752.701236493536, 59759.70120149459, 59827.70086149628, 59842.80314393688, 1323.6426164935413, 1343.6909510812568, 1345.2063282663275, 1491.5003473836168, 1492.970140597182, 22390.0, 29098.7090129096, 29148.854255728587, 29469.0, 30624.387512250076, 53006.73496632492, 53339.0, 54736.0, 54757.72621136869, 54889.0, 614972.0, 617064.0, 627316.0, 629420.0, 630464.0, 25606.871965640054, 31314.0, 39269.64382137081, 39469.60530394774, 40051.59948400595, 30257.546136808407, 34503.96488105361, 42503.7874810624, 42805.57194428016, 42811.7156485306, 37140.07149821168, 37394.43908341431, 37508.812455938634, 37823.24353512859, 38795.0, 37059.25881482409, 37360.0, 37469.62530374771, 38016.429753553166, 38167.04582385463, 37164.88505344844, 37248.0, 37285.69500067593, 37598.43602345912, 37707.86876393713, 30649.233769155948, 32838.179045524055, 32934.34131317408, 32963.8351808249, 33017.834910825295, 24112.51774964526, 24402.633960490963, 24433.51132977366, 24924.37689057788, 25030.874845625654], "value": 18783.61450640418, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.98291898361714, 400.075824844609, 405.5073086200268, 405.6774863983133, 410.7740742591574, 16848.663026739458, 20416.89791551042, 20664.690029649566, 21164.682529762045, 21793.801340926242, 20941.790582094174, 20947.68578471324, 21440.03519841607, 21496.785032149673, 21507.03218355174, 5091.745412729368, 5431.995080910025, 5436.728163591824, 5437.211604317372, 5441.646292990943, 4717.504662010474, 5331.973340133294, 5332.280142180794, 5336.946630533703, 5401.918971215435, 5262.973685131588, 5406.378266499363, 5414.512693857552, 5436.3476382833915, 5444.265024221719, 5357.169638705968, 5428.864278393052, 5440.945590544084, 5443.1835224716215, 5451.0, 5058.367704036996, 5236.633435659505, 5268.5521730652945, 5270.077736396115, 5280.07598670235, 13321.000924930571, 13874.167549946935, 14048.367823447968, 14070.155790652594, 14126.92936535321, 2094.350751267112, 2318.26974503031, 2344.1443872986406, 2359.834811563191, 2613.764761171504, 10363.48182590875, 11620.360880151587, 11939.283642981449, 14821.333040013225, 15448.922755386151, 1672.2474886301213, 1753.7720096387516, 1796.2455768577229, 1829.0945981738992, 1866.7759868815692, 127493.72506274912, 130205.0, 134434.6556534432, 136166.3191684045, 136467.0, 11867.228630139014, 11875.821862672066, 11903.821442678367, 11936.28382297065, 12087.214331068453, 15629.0, 15667.0, 15694.372225111096, 15720.92139539295, 15740.055596664237, 7282.70869165238, 9034.593443294936, 9100.362974591715, 9109.0, 9183.081691830763, 8341.917088390222, 8492.74521764348, 9078.364514484056, 9103.998560158378, 9199.218066464291, 3815.6947444204397, 4665.86002419928, 4693.296005599187, 4701.341812146302, 4765.547273009059, 1803.431918945528, 1839.9521053681954, 1920.836728878033, 1963.7054441833554, 1976.1798853475934, 841.8610929196614, 905.8686490458847, 978.1978777402604, 1009.6516701738036, 1067.8451624514403, 20240.291589794582, 21892.0, 21903.0, 21972.340829775134, 21984.230551930606, 907.2605826251583, 945.7871978804714, 967.5887747707337, 969.8943204746595, 985.5531367504537, 9727.56225969833, 10982.523263189985, 11322.204755844965, 11560.190786645022, 11598.448341364887, 585922.0, 594559.0, 635821.0, 639851.0, 640852.0, 129307.70692293145, 136398.31800840932, 136599.0, 136836.0, 138946.30526847302, 881.7354793561906, 1032.0917592518686, 1038.5274700011628, 1055.3773273768513, 1057.5981127171626, 18216.36242731498, 19016.809831901504, 19095.04524773728, 19201.0, 19244.711329330352, 49955.50044499509, 51221.0, 52308.0, 53283.0, 54373.72813135909, 77187.228127718, 77691.0, 78077.219227807, 78260.0, 80560.79158812505, 8229.806678031771, 8298.70954516589, 8427.452215605967, 8510.106438824081, 8708.129187081242, 11056.778864422828, 12613.684657883627, 12682.239065656091, 12991.28547929835, 13038.543650972171, 10758.354498730101, 11109.722256943318, 11143.832842507207, 11170.553177872795, 11196.720081998015, 9641.807163856824, 10893.237473376788, 11149.442527873412, 11210.103191744804, 11227.494762735532, 11279.436028198721, 11334.716632084264, 11417.828732568853, 11638.185327027366, 11715.121365897421, 8300.2114799094, 8314.210150035737, 8381.37139714536, 8385.916140838513, 8441.15588441151, 10446.373217606968, 11078.390688512209, 11285.7742845141, 11372.658820235407, 11372.97643212115], "value": 9533.704125193506, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1417.18511855683, 1582.493602047345, 1623.0099639220077, 1853.481025312912, 1985.1067019841075, 7797.53214807112, 8598.05421403646, 8799.120087991198, 8807.86788198176, 8817.603207855645, 8410.579471026454, 8631.784205394868, 8633.69782057628, 8643.481391116537, 8719.389642725011, 2149.1833103420677, 2221.7889300516504, 2223.2218723446827, 2429.2226487524044, 2472.431340791619, 2226.1318085946496, 2418.407490164912, 2505.649209110726, 2513.7611926866916, 2516.3834860459124, 2422.697162854643, 2455.631655251718, 2459.44662450948, 2467.703875534938, 2477.3682710908784, 2136.572685462898, 2433.987830060847, 2437.2688193542044, 2443.376938880583, 2444.4499987502845, 2389.7371289158177, 2485.701715794098, 2488.1789009626777, 2495.9376015599573, 2501.6122501012374, 8617.956910215471, 8687.131286871325, 8696.478211307342, 8705.651773929036, 8708.17272359125, 5227.477252274742, 5370.462953704638, 5385.623006389555, 5420.457954204588, 5437.374701909252, 3391.4404123319623, 3467.826608669556, 3603.0, 3613.5483064616924, 3621.0947263184203, 976.5898322704481, 1032.339302846179, 1041.3335465302212, 1045.095991966943, 1054.3357684658615, 11735.178537502377, 11756.0, 11777.587784427504, 11781.410929453494, 11783.410829458579, 4741.1465936131135, 4789.485187466462, 4883.536064073907, 4895.681780684244, 4921.237208232729, 9958.751031224278, 9996.600135994478, 10006.0, 10006.049425304584, 10008.09927106564, 5403.567714582824, 5434.130539113723, 5443.292371991695, 5448.291722076105, 5460.590455715837, 4787.257975013878, 4841.176999910023, 4904.337914381513, 4954.232094025432, 4959.752012399438, 4034.6772258219858, 4059.208454351432, 4088.1210539733506, 4139.317012692933, 4171.520275168334, 1332.733453309342, 1344.0793056756154, 1344.5764584155959, 1359.6274500995826, 1365.2491129878526, 876.6142897125244, 901.9278457723366, 924.9075092490697, 959.1369761262625, 961.427950369529, 5862.941370586324, 5921.703914804242, 5933.495652869555, 6044.1840351552855, 6197.876042479215, 447.7313611832939, 462.8310666606664, 471.516695387224, 472.1100725133149, 473.20973973464396, 3878.864239751595, 3951.940720889189, 4004.599540045972, 4048.473698419187, 4108.137291168829, 7119.537230080028, 10705.0, 10836.187285953427, 10859.891401086046, 10865.402402867918, 10448.216383771096, 10591.4704264788, 10609.522571484302, 10706.250562460553, 10726.463676816127, 7654.846903061907, 8048.476849004796, 8073.959630201812, 8206.548639824747, 8217.260446559838, 4813.927791083207, 4994.9750251248515, 6030.7286172121485, 6032.306284777307, 6142.293636231891, 9346.345755797029, 9543.0, 9826.75433114178, 9953.701388958343, 9966.601335946481, 9047.909520904706, 9905.603775848886, 10054.64808731691, 10071.69784906454, 10072.446015469219, 6970.128733908262, 7326.413886889142, 7594.392448604209, 7604.087509498895, 7605.809854753676, 8026.277635012877, 8086.3935204861045, 8105.75682729519, 8209.096999330188, 8232.62953167097, 7295.416366690546, 7359.926400735924, 7437.7396791114215, 7449.404047676281, 7539.585322807079, 7177.1746249182015, 7214.89177662346, 7343.8898416522725, 7407.370373518096, 7416.406687465098, 6941.514094013371, 6996.895046574204, 7043.542169758949, 7045.330693584307, 7051.1186101737285, 4833.589144922721, 4889.535494128052, 4930.704157750546, 5008.874778130576, 5050.393952725696, 4984.526469985346, 5036.29491871131, 5089.083964886356, 5112.130937740668, 5154.974225128851], "value": 4523.34338747517, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.8697238353207, 303.65686773945436, 323.103388098028, 324.28656954699665, 327.6314146585092, 4091.7520156352302, 5221.686698798075, 5240.816571420001, 5241.0828105081555, 5312.707801070945, 4517.344984977184, 5051.520105589963, 5253.921191182126, 5262.578993680506, 5275.70983595903, 3351.497275408696, 4005.698148101864, 4054.4729185205883, 4063.573324800898, 4065.898352541188, 3962.7584218558095, 4043.272211002033, 4067.430559721641, 4102.323116685743, 4108.77401742904, 3840.4047372657283, 4014.739041962263, 4024.798760062, 4060.1473690525027, 4061.634452899253, 3251.430999575065, 4013.9397909031386, 4026.2148880968225, 4044.786564030808, 4058.9852536865933, 2735.8358498489956, 3485.989063171677, 3486.18074752433, 3506.947915625328, 3538.9646103538894, 4395.978020109911, 5043.798248070073, 5100.540951314362, 5113.897722045538, 5138.820141295074, 4024.315866302706, 4604.342436722777, 4622.445306563234, 4630.560096790799, 4642.9071418571775, 4352.216601011816, 4838.959623680926, 5318.787248510055, 5555.8888822223735, 5560.636729390034, 3588.3672928817327, 3815.866444674451, 3834.348160812641, 3839.8272077756287, 3860.9613903861164, 5105.03004429161, 5685.0, 5806.680632565207, 5871.970640146815, 5893.999999999957, 2981.7167369099902, 3929.328056921264, 3933.8033098344968, 3940.3498422760495, 3953.2686453006286, 3234.272288735016, 3781.9032480580872, 3940.704447166474, 3968.543617483969, 3968.980155099206, 4577.3362862384765, 4580.358749775034, 4592.609628181575, 4598.0, 4625.352450656911, 4611.924633914685, 4649.116667833101, 5175.378954525406, 5643.181738647873, 5671.412004638708, 1231.5320178332356, 1246.569933372985, 1257.4027337014845, 1329.5147271245926, 1388.9236092015037, 910.4537277633499, 935.396669148409, 947.7772723409993, 959.1751094059103, 960.6973803251954, 4648.814047438133, 4659.2312268476, 5542.3626282977975, 5561.026820306431, 5577.498025177753, 4948.331975183376, 5672.574556908248, 5776.653400795965, 6108.3280839107665, 6184.536159787944, 5010.599152067826, 5290.523852853262, 5520.282363292747, 5531.0, 5556.333100070044, 3309.470484722433, 3328.951380315193, 3329.5505106810756, 3342.983285083559, 3349.6650334966307, 6283.37166283368, 6572.835679107965, 6574.013897915255, 6589.967050164814, 6598.769043083469, 6121.449069583669, 6413.414408697803, 6445.581037232565, 6461.0, 6482.35176482348, 5476.315460567429, 6559.704813283413, 6578.967105164444, 6582.3746744059235, 6606.537542371988, 5040.722760248222, 5263.868403289948, 5501.669899806025, 5771.5671324649975, 6304.558680892477, 4812.171374877524, 5739.799107031234, 5756.30924289088, 5769.249997500298, 5774.220480235114, 5049.434675250684, 5155.767990440364, 5362.410134885082, 5730.627509211888, 6134.38656134383, 3731.5708693500596, 3891.8637847675195, 3909.941350879796, 3921.745086569364, 3956.4263181837896, 4658.161530924466, 4898.608111351012, 5603.215549822947, 5626.833669037532, 5773.3072031356505, 5524.806631767869, 5530.585206109477, 5576.4664717203095, 5589.959664722428, 5618.831435056954, 4572.028181681933, 4639.675222734376, 5387.946120538744, 5462.726863656881, 5518.889622207453, 4321.805518751601, 5247.842564723064, 5396.271503346998, 5404.7297635118875, 5499.285092938053, 4868.634852386156, 4936.629752768628, 5041.899162016666, 5474.562035037267, 5564.805231816867, 4813.4945830688675, 5191.456186468527, 5410.377806552139, 5485.177223416519, 5504.2018907257425], "value": 4115.022637423316, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.6951017776735, 675.5878913862543, 689.8516818883938, 733.1129333506453, 734.6179986407066, 14359.92820035901, 14410.711785764279, 14872.47946321879, 15515.767263491056, 15525.301361438735, 14218.07582507139, 14232.217228052454, 14305.284735763196, 14316.35576399062, 14341.928290358534, 741.0995640297051, 762.9814198045616, 886.5434301334818, 928.6981730937472, 933.551895090357, 771.5756334016297, 929.0941332201083, 934.2525979216633, 934.3739694404765, 937.4890684576887, 774.7017398301638, 981.8281800684887, 991.0634450444333, 992.5781542844298, 996.4718699089494, 943.273679266964, 979.1139019187611, 984.1732944326728, 984.5618699678646, 986.758244230161, 721.4228617106294, 725.7713820146689, 727.5125665803924, 728.6611725547646, 728.7595093619093, 15176.317065732068, 15236.847631523653, 15245.771313430307, 15247.23763811816, 15265.23673816316, 12574.308413037279, 12723.491060357575, 12945.352732363439, 13020.414081366362, 13048.956083513296, 9935.701928942144, 9995.400275983464, 10030.699079027641, 10308.123809476203, 10690.358578485315, 3434.9656503434894, 3439.2777516721517, 3525.806080665562, 3531.3996620574308, 3604.477350784148, 71945.84162475081, 78202.6089869547, 78863.0, 81046.0, 81397.0, 9631.470269135265, 9739.56171972263, 9792.314537982273, 9832.0, 9992.400455972665, 21830.126794928343, 22063.33809985703, 22101.889490552767, 22107.226247081275, 22356.105755769586, 7596.191004667092, 7673.654685539164, 7740.922590774133, 8041.236082572146, 8043.19568043191, 4029.7985100744845, 4153.91692166155, 4156.625903668684, 4159.667226621863, 4221.113566151143, 3582.140286331262, 3614.313280476701, 3652.3060618482937, 3654.250878569887, 3657.0, 569.4504802865255, 571.9656820590694, 649.7790751144634, 658.8385845467891, 662.4700239808134, 523.2857149990311, 595.9046552551657, 597.9222701048923, 602.764921680545, 626.4424662050778, 7979.441439099323, 8276.08963014068, 8386.0, 8448.619812108469, 8471.152884711479, 576.6165499942491, 588.6997631208029, 592.8331946734692, 603.2820943077724, 614.7203022624695, 3170.4134437437983, 3206.9839650802064, 3212.229065024424, 3253.6420993690676, 3256.446404111307, 96111.07777844404, 96309.0, 96318.0, 100822.98354032879, 108541.0, 77243.0, 77688.22311776923, 78215.0, 78454.82317765238, 78542.42915141779, 45901.770491147334, 68990.65504672444, 70681.0, 71414.9287760684, 71723.64138179275, 8137.918620813716, 8155.388345873965, 8233.46482478637, 8329.875051874105, 8357.582120893809, 42114.789426052674, 42228.36657450202, 43588.0, 44089.77955110204, 44399.22403103969, 56985.715071424376, 57150.0, 60356.39643603683, 63641.0, 63728.0, 10326.01902819231, 10429.061384475433, 10837.349759014485, 11285.097192224439, 11562.248453850472, 11849.0, 11925.582604608797, 12119.697007574881, 12165.817512737493, 12219.816702749644, 11232.943835280772, 11278.0, 11335.433228338716, 11643.5342586299, 11711.824322634997, 10766.515506802056, 10993.395363255098, 11110.833337499782, 11320.037796787365, 11960.76078478443, 8939.284857211538, 10629.681109566725, 11035.282706624044, 11046.502907369028, 11103.611373601885, 9181.954090229507, 9637.22902167839, 9680.031996800546, 9703.951480242553, 9934.900650993397, 9452.669156579486, 9554.28342874301, 9613.615455381707, 9637.37357071788, 9653.324267301221], "value": 6837.957412404691, "unit": "score"}], "name": "dromaeo_css", "value": 7112.534112821112, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 107.0, 115.0, 114.0, 116.0, 122.0, 166.0, 140.0, 114.0], "value": 122.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 121.0, 161.0, 157.0, 121.0, 139.0, 121.0, 154.0, 156.0, 119.0], "value": 140.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 185.0, 190.0, 186.0, 185.0, 185.0, 188.0, 182.0, 186.0], "value": 185.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 77.0, 73.0, 153.0, 57.0, 55.0, 61.0, 66.0, 141.0, 60.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 138.0, 77.0, 84.0, 94.0, 125.0, 79.0, 77.0, 139.0], "value": 99.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 122.0, 89.0, 87.0, 89.0, 121.0, 90.0, 87.0, 88.0, 85.0], "value": 94.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 97.0, 141.0, 150.0, 113.0, 96.0, 94.0, 100.0, 94.0], "value": 107.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 95.0, 86.0, 83.0, 84.0, 86.0, 85.0, 86.0, 86.0, 85.0], "value": 86.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 66.0, 64.0, 63.0, 63.0, 65.0, 83.0, 60.0, 55.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 72.0, 74.0, 75.0, 68.0, 68.0, 70.0, 67.0, 68.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 82.0, 82.0, 83.0, 93.0, 105.0, 106.0, 82.0, 83.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 156.0, 162.0, 151.0, 136.0, 149.0, 153.0, 149.0, 185.0, 139.0], "value": 152.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 168.0, 169.0, 169.0, 168.0, 170.0, 167.0, 168.0, 170.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 69.0, 69.0, 68.0, 68.0, 69.0, 70.0, 68.0, 69.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1527.9000000000003, "alertThreshold": 2.0}]} 07:39:43 INFO - Return code: 0 07:39:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:39:43 ERROR - # TBPL SUCCESS # 07:39:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:39:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:39:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:39:43 INFO - 2016-04-30 07:39:43.913 screenresolution[1683:10482] starting screenresolution argv=screenresolution get 07:39:43 INFO - 2016-04-30 07:39:43.916 screenresolution[1683:10482] Display 0: 1600x1200x32@60 07:39:43 INFO - 2016-04-30 07:39:43.922 screenresolution[1684:10486] starting screenresolution argv=screenresolution list 07:39:43 INFO - Available Modes on Display 0 07:39:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:39:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:39:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:39:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:39:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:39:44 INFO - Intel Iris: 07:39:44 INFO - Chipset Model: Intel Iris 07:39:44 INFO - Type: GPU 07:39:44 INFO - Bus: Built-In 07:39:44 INFO - VRAM (Dynamic, Max): 1536 MB 07:39:44 INFO - Vendor: Intel (0x8086) 07:39:44 INFO - Device ID: 0x0a2e 07:39:44 INFO - Revision ID: 0x0009 07:39:44 INFO - Displays: 07:39:44 INFO - Display: 07:39:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:39:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:39:44 INFO - Display Serial Number: 1600x1200 60 07:39:44 INFO - Main Display: Yes 07:39:44 INFO - Mirror: Off 07:39:44 INFO - Online: Yes 07:39:44 INFO - Rotation: Supported 07:39:44 INFO - Return code: 0 07:39:44 INFO - Running post-action listener: _resource_record_post_action 07:39:44 INFO - Running post-run listener: _resource_record_post_run 07:39:45 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 32542208; Write bytes: 653733888; Read time: 327; Write time: 1879 07:39:45 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 350982656; Write bytes: 345548288; Read time: 27657; Write time: 647 07:39:45 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 30760448; Write bytes: 297396736; Read time: 309; Write time: 1196 07:39:45 INFO - Running post-run listener: _upload_blobber_files 07:39:45 INFO - Blob upload gear active. 07:39:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:39:45 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 07:39:45 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:39:45 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:39:45 INFO - (blobuploader) - INFO - Open directory for files ... 07:39:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:39:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:39:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:46 INFO - (blobuploader) - INFO - Done attempting. 07:39:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:39:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:39:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:47 INFO - (blobuploader) - INFO - Done attempting. 07:39:47 INFO - (blobuploader) - INFO - Iteration through files over. 07:39:47 INFO - Return code: 0 07:39:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:39:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:39:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d085c2f997307f9aeb3eb43af036d8a92442d6d7badd3f1294ec870c50d9891c4c6a87abf15e1f8c0f704436c7924db7c57ce3612c5dcf133bbb001a9f09d0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f36807fc0c09de6101332f357edbbcaa943e2a204c378c7b7457555f6112e1573b88a22df2ae94a4fb96116c9a0ee4eb46d9f6194a9c7c89545dc8b41375808"} 07:39:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:39:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:39:47 INFO - Contents: 07:39:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d085c2f997307f9aeb3eb43af036d8a92442d6d7badd3f1294ec870c50d9891c4c6a87abf15e1f8c0f704436c7924db7c57ce3612c5dcf133bbb001a9f09d0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f36807fc0c09de6101332f357edbbcaa943e2a204c378c7b7457555f6112e1573b88a22df2ae94a4fb96116c9a0ee4eb46d9f6194a9c7c89545dc8b41375808"} 07:39:47 INFO - Running post-run listener: copy_logs_to_upload_dir 07:39:47 INFO - Copying logs to upload dir... 07:39:47 INFO - mkdir: /builds/slave/test/build/upload/logs 07:39:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.282721 ========= master_lag: 1.75 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-04-30 07:39:47.900970) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-30 07:39:47.904217) ========= 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.mb7JN4myA3/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.azen0aCO5y/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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/Mozilla-Aurora/sha512/4d085c2f997307f9aeb3eb43af036d8a92442d6d7badd3f1294ec870c50d9891c4c6a87abf15e1f8c0f704436c7924db7c57ce3612c5dcf133bbb001a9f09d0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f36807fc0c09de6101332f357edbbcaa943e2a204c378c7b7457555f6112e1573b88a22df2ae94a4fb96116c9a0ee4eb46d9f6194a9c7c89545dc8b41375808"} build_url:https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010974 build_url: 'https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d085c2f997307f9aeb3eb43af036d8a92442d6d7badd3f1294ec870c50d9891c4c6a87abf15e1f8c0f704436c7924db7c57ce3612c5dcf133bbb001a9f09d0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f36807fc0c09de6101332f357edbbcaa943e2a204c378c7b7457555f6112e1573b88a22df2ae94a4fb96116c9a0ee4eb46d9f6194a9c7c89545dc8b41375808"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-30 07:39:47.947907) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-30 07:39:47.948258) ========= 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.mb7JN4myA3/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.azen0aCO5y/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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.005219 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-30 07:39:47.997869) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-30 07:39:47.998188) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-30 07:39:47.998550) ========= ========= Total master_lag: 12.50 =========