builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0143 starttime: 1461921454.42 results: success (0) buildid: 20160429004052 builduid: cf844daba61045de8c42c78cef417c6e revision: 6e453fe828bf9054b6db0307ffb034d3acceb9c9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 02:17:34.418267) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 02:17:34.418734) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 02:17:34.419041) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 02:17:34.438511) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 02:17:34.438837) ========= 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.MBTaEIFiae/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.bXM4vkQy4K/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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-29 02:17:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.7M=0.001s 2016-04-29 02:17:34 (14.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125759 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 02:17:34.582218) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 02:17:34.582549) ========= 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.MBTaEIFiae/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.bXM4vkQy4K/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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.027162 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 02:17:34.631012) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 02:17:34.631401) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6e453fe828bf9054b6db0307ffb034d3acceb9c9 --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 6e453fe828bf9054b6db0307ffb034d3acceb9c9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MBTaEIFiae/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.bXM4vkQy4K/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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-29 02:17:34,698 truncating revision to first 12 chars 2016-04-29 02:17:34,698 Setting DEBUG logging. 2016-04-29 02:17:34,698 attempt 1/10 2016-04-29 02:17:34,698 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6e453fe828bf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-29 02:17:35,362 unpacking tar archive at: mozilla-aurora-6e453fe828bf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.913053 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 02:17:35.561924) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 02:17:35.562255) ========= script_repo_revision: 6e453fe828bf9054b6db0307ffb034d3acceb9c9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 02:17:35.562662) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 02:17:35.562953) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 02:17:35.577141) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 38 secs) (at 2016-04-29 02:17:35.577553) ========= /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.MBTaEIFiae/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.bXM4vkQy4K/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:17:35 INFO - MultiFileLogger online at 20160429 02:17:35 in /builds/slave/test 02:17:35 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 02:17:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:17:35 INFO - {'append_to_log': False, 02:17:35 INFO - 'base_work_dir': '/builds/slave/test', 02:17:35 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:17:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:17:35 INFO - 'branch': 'Mozilla-Aurora', 02:17:35 INFO - 'buildbot_json_path': 'buildprops.json', 02:17:35 INFO - 'config_files': ('talos/mac_config.py',), 02:17:35 INFO - 'default_actions': ('clobber', 02:17:35 INFO - 'read-buildbot-config', 02:17:35 INFO - 'download-and-extract', 02:17:35 INFO - 'populate-webroot', 02:17:35 INFO - 'create-virtualenv', 02:17:35 INFO - 'install', 02:17:35 INFO - 'run-tests'), 02:17:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:17:35 INFO - 'download_minidump_stackwalk': True, 02:17:35 INFO - 'download_symbols': 'ondemand', 02:17:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:17:35 INFO - 'tooltool.py': '/tools/tooltool.py', 02:17:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:17:35 INFO - '/tools/misc-python/virtualenv.py')}, 02:17:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:17:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:17:35 INFO - 'installer_path': 'installer.exe', 02:17:35 INFO - 'log_level': 'info', 02:17:35 INFO - 'log_name': 'talos', 02:17:35 INFO - 'log_to_console': True, 02:17:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:17:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:17:35 INFO - 'opt_config_files': (), 02:17:35 INFO - 'pip_index': False, 02:17:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:17:35 INFO - 'cmd': ('bash', 02:17:35 INFO - '-c', 02:17:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:17:35 INFO - 'enabled': True, 02:17:35 INFO - 'halt_on_failure': False, 02:17:35 INFO - 'name': 'check_screen_resolution'},), 02:17:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:17:35 INFO - 'cmd': ('bash', 02:17:35 INFO - '-c', 02:17:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:17:35 INFO - 'enabled': True, 02:17:35 INFO - 'halt_on_failure': False, 02:17:35 INFO - 'name': 'check_screen_resolution'},), 02:17:35 INFO - 'run_cmd_checks_enabled': True, 02:17:35 INFO - 'sps_profile': False, 02:17:35 INFO - 'sps_profile_interval': 0, 02:17:35 INFO - 'suite': 'dromaeojs', 02:17:35 INFO - 'system_bits': '32', 02:17:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:17:35 INFO - 'title': 't-yosemite-r7-0143', 02:17:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:17:35 INFO - 'use_talos_json': True, 02:17:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:17:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:17:35 INFO - 'work_dir': 'build'} 02:17:35 INFO - ##### 02:17:35 INFO - ##### Running clobber step. 02:17:35 INFO - ##### 02:17:35 INFO - Running pre-action listener: _resource_record_pre_action 02:17:35 INFO - Running main action method: clobber 02:17:35 INFO - rmtree: /builds/slave/test/build 02:17:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:17:38 INFO - Running post-action listener: _resource_record_post_action 02:17:38 INFO - ##### 02:17:38 INFO - ##### Running read-buildbot-config step. 02:17:38 INFO - ##### 02:17:38 INFO - Running pre-action listener: _resource_record_pre_action 02:17:38 INFO - Running main action method: read_buildbot_config 02:17:38 INFO - Using buildbot properties: 02:17:38 INFO - { 02:17:38 INFO - "project": "", 02:17:38 INFO - "product": "firefox", 02:17:38 INFO - "script_repo_revision": "production", 02:17:38 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:17:38 INFO - "repository": "", 02:17:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:17:38 INFO - "buildid": "20160429004052", 02:17:38 INFO - "stage_platform": "macosx64", 02:17:38 INFO - "basedir": "/builds/slave/test", 02:17:38 INFO - "nightly_build": "True", 02:17:38 INFO - "buildnumber": 331, 02:17:38 INFO - "slavename": "t-yosemite-r7-0143", 02:17:38 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:17:38 INFO - "platform": "yosemite_r7", 02:17:38 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:17:38 INFO - "branch": "mozilla-aurora", 02:17:38 INFO - "repo_path": "releases/mozilla-aurora", 02:17:38 INFO - "revision": "6e453fe828bf9054b6db0307ffb034d3acceb9c9", 02:17:38 INFO - "pgo_build": "False", 02:17:38 INFO - "builduid": "cf844daba61045de8c42c78cef417c6e", 02:17:38 INFO - "slavebuilddir": "test" 02:17:38 INFO - } 02:17:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:17:38 INFO - Running post-action listener: _resource_record_post_action 02:17:38 INFO - ##### 02:17:38 INFO - ##### Running download-and-extract step. 02:17:38 INFO - ##### 02:17:38 INFO - Running pre-action listener: _resource_record_pre_action 02:17:38 INFO - Running main action method: download_and_extract 02:17:38 INFO - mkdir: /builds/slave/test/build/tests 02:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:17:38 INFO - https://queue.taskcluster.net/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:17:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:17:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:17:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/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 02:17:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/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 02:17:54 INFO - Downloaded 1199 bytes. 02:17:54 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:17:54 INFO - Using the following test package requirements: 02:17:54 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:17:54 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:17:54 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:17:54 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:17:54 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:17:54 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:17:54 INFO - u'jsshell-mac.zip'], 02:17:54 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:17:54 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:17:54 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:17:54 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:17:54 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:17:54 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:17:54 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:17:54 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:17:54 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:17:54 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:17:54 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:17:54 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:17:54 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')]} 02:17:54 INFO - https://queue.taskcluster.net/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:17:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:17:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:17:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/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 02:17:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/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 02:17:56 INFO - Downloaded 18110559 bytes. 02:17:56 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'] 02:17:56 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 02:17:59 INFO - Return code: 0 02:17:59 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 02:17:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:17:59 INFO - https://queue.taskcluster.net/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:17:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:17:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:17:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/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 02:17:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/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 02:18:12 INFO - Downloaded 18110559 bytes. 02:18:12 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'] 02:18:12 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 02:18:15 INFO - Return code: 0 02:18:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:15 INFO - https://queue.taskcluster.net/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:18:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:18:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:18:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/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 02:18:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/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 02:18:16 INFO - Downloaded 10840145 bytes. 02:18:16 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'] 02:18:16 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 02:18:16 INFO - Return code: 0 02:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:16 INFO - https://queue.taskcluster.net/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:18:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:18:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:18:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:18:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OJrOJL_vRSC9f0xPSoQFjA/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 02:18:22 INFO - Downloaded 114211246 bytes. 02:18:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:18:22 INFO - mkdir: /builds/slave/test/properties 02:18:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:18:22 INFO - Writing to file /builds/slave/test/properties/build_url 02:18:22 INFO - Contents: 02:18:22 INFO - build_url:https://queue.taskcluster.net/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:18:23 INFO - Running post-action listener: _resource_record_post_action 02:18:23 INFO - Running post-action listener: set_extra_try_arguments 02:18:23 INFO - ##### 02:18:23 INFO - ##### Running populate-webroot step. 02:18:23 INFO - ##### 02:18:23 INFO - Running pre-action listener: _resource_record_pre_action 02:18:23 INFO - Running main action method: populate_webroot 02:18:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:18:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:18:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:18:23 INFO - '--tppagecycles', 02:18:23 INFO - '7'], 02:18:23 INFO - 'tests': ['tsvgm']}}, 02:18:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:18:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:18:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:18:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:18:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:18:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:18:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:23 INFO - 'talos_options': ['--disable-e10s'], 02:18:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:18:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:18:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:23 INFO - 'talos_options': ['--disable-e10s'], 02:18:23 INFO - 'tests': ['damp', 'tps']}, 02:18:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:23 INFO - 'tests': ['damp', 'tps']}, 02:18:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:18:23 INFO - 'tests': ['dromaeo_dom']}, 02:18:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:18:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:18:23 INFO - 'tests': ['a11yr', 02:18:23 INFO - 'ts_paint', 02:18:23 INFO - 'tpaint', 02:18:23 INFO - 'sessionrestore', 02:18:23 INFO - 'sessionrestore_no_auto_restore', 02:18:23 INFO - 'tabpaint']}, 02:18:23 INFO - 'other-e10s': {'tests': ['a11yr', 02:18:23 INFO - 'ts_paint', 02:18:23 INFO - 'tpaint', 02:18:23 INFO - 'sessionrestore', 02:18:23 INFO - 'sessionrestore_no_auto_restore', 02:18:23 INFO - 'tabpaint']}, 02:18:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:18:23 INFO - 'ts_paint', 02:18:23 INFO - 'tpaint', 02:18:23 INFO - 'sessionrestore', 02:18:23 INFO - 'sessionrestore_no_auto_restore', 02:18:23 INFO - 'tabpaint']}, 02:18:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:18:23 INFO - 'ts_paint', 02:18:23 INFO - 'tpaint', 02:18:23 INFO - 'sessionrestore', 02:18:23 INFO - 'sessionrestore_no_auto_restore', 02:18:23 INFO - 'tabpaint']}, 02:18:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:18:23 INFO - 'tests': ['a11yr', 02:18:23 INFO - 'ts_paint', 02:18:23 INFO - 'tpaint', 02:18:23 INFO - 'sessionrestore', 02:18:23 INFO - 'sessionrestore_no_auto_restore', 02:18:23 INFO - 'tabpaint']}, 02:18:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:18:23 INFO - 'tests': ['a11yr', 02:18:23 INFO - 'ts_paint', 02:18:23 INFO - 'tpaint', 02:18:23 INFO - 'sessionrestore', 02:18:23 INFO - 'sessionrestore_no_auto_restore', 02:18:23 INFO - 'tabpaint']}, 02:18:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:18:23 INFO - 'tests': ['tsvgx', 02:18:23 INFO - 'tsvgr_opacity', 02:18:23 INFO - 'tart', 02:18:23 INFO - 'tscrollx', 02:18:23 INFO - 'cart']}, 02:18:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:18:23 INFO - 'tsvgr_opacity', 02:18:23 INFO - 'tart', 02:18:23 INFO - 'tscrollx', 02:18:23 INFO - 'cart']}, 02:18:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:23 INFO - 'talos_options': ['--disable-e10s'], 02:18:23 INFO - 'tests': ['tp5o']}, 02:18:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:23 INFO - 'tests': ['tp5o']}, 02:18:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:23 INFO - 'talos_options': ['--disable-e10s', 02:18:23 INFO - '--xperf_path', 02:18:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:18:23 INFO - 'tests': ['tp5n']}, 02:18:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:23 INFO - 'talos_options': ['--xperf_path', 02:18:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:18:23 INFO - 'tests': ['tp5n']}}, 02:18:23 INFO - 'talos.zip': {'path': '', 02:18:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:18:23 INFO - Running post-action listener: _resource_record_post_action 02:18:23 INFO - ##### 02:18:23 INFO - ##### Running create-virtualenv step. 02:18:23 INFO - ##### 02:18:23 INFO - Running pre-action listener: _resource_record_pre_action 02:18:23 INFO - Running main action method: create_virtualenv 02:18:23 INFO - Creating virtualenv /builds/slave/test/build/venv 02:18:23 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 02:18:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:18:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:18:23 INFO - Using real prefix '/tools/python27' 02:18:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:18:24 INFO - Installing distribute.............................................................................................................................................................................................done. 02:18:27 INFO - Installing pip.................done. 02:18:27 INFO - Return code: 0 02:18:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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')]} 02:18:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1024bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe56a558730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10235ee00>, '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 0x1021c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102359b20>, '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.MBTaEIFiae/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.bXM4vkQy4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:18: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', 'pip>=1.5'] in /builds/slave/test/build 02:18: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 pip>=1.5 02:18:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MBTaEIFiae/Render', 02:18:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:27 INFO - 'HOME': '/Users/cltbld', 02:18:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:27 INFO - 'LOGNAME': 'cltbld', 02:18:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:27 INFO - 'MOZ_NO_REMOTE': '1', 02:18:27 INFO - 'NO_EM_RESTART': '1', 02:18:27 INFO - 'PAGER': '/bin/cat', 02:18:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:27 INFO - 'PWD': '/builds/slave/test', 02:18:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:27 INFO - 'SHELL': '/bin/bash', 02:18:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXM4vkQy4K/Listeners', 02:18:27 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:18:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:27 INFO - 'USER': 'cltbld', 02:18:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:27 INFO - 'XPC_FLAGS': '0x0', 02:18:27 INFO - 'XPC_SERVICE_NAME': '0', 02:18:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:27 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 02:18:27 INFO - Cleaning up... 02:18:27 INFO - Return code: 0 02:18:27 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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')]} 02:18:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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', '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 0x1024bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe56a558730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10235ee00>, '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 0x1021c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102359b20>, '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.MBTaEIFiae/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.bXM4vkQy4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:18: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', 'psutil>=0.7.1'] in /builds/slave/test/build 02:18: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 psutil>=0.7.1 02:18:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MBTaEIFiae/Render', 02:18:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:27 INFO - 'HOME': '/Users/cltbld', 02:18:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:27 INFO - 'LOGNAME': 'cltbld', 02:18:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:27 INFO - 'MOZ_NO_REMOTE': '1', 02:18:27 INFO - 'NO_EM_RESTART': '1', 02:18:27 INFO - 'PAGER': '/bin/cat', 02:18:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:27 INFO - 'PWD': '/builds/slave/test', 02:18:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:27 INFO - 'SHELL': '/bin/bash', 02:18:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXM4vkQy4K/Listeners', 02:18:27 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:18:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:27 INFO - 'USER': 'cltbld', 02:18:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:27 INFO - 'XPC_FLAGS': '0x0', 02:18:27 INFO - 'XPC_SERVICE_NAME': '0', 02:18:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:27 INFO - Downloading/unpacking psutil>=0.7.1 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:18:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:18:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:18:30 INFO - Installing collected packages: psutil 02:18:30 INFO - Running setup.py install for psutil 02:18:30 INFO - building 'psutil._psutil_osx' extension 02:18:30 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 02:18:31 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 02:18:31 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 02:18:31 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 02:18:31 INFO - building 'psutil._psutil_posix' extension 02:18:31 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 02:18:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:18:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:18:31 INFO - ^ 02:18:31 INFO - 1 warning generated. 02:18:31 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 02:18:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:18:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:18:31 INFO - Successfully installed psutil 02:18:31 INFO - Cleaning up... 02:18:32 INFO - Return code: 0 02:18:32 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:18:32 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')]} 02:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:32 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')]} 02:18:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:32 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 0x1024bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe56a558730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10235ee00>, '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 0x1021c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102359b20>, '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.MBTaEIFiae/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.bXM4vkQy4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:18:32 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 02:18:32 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 02:18:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MBTaEIFiae/Render', 02:18:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:32 INFO - 'HOME': '/Users/cltbld', 02:18:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:32 INFO - 'LOGNAME': 'cltbld', 02:18:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:32 INFO - 'MOZ_NO_REMOTE': '1', 02:18:32 INFO - 'NO_EM_RESTART': '1', 02:18:32 INFO - 'PAGER': '/bin/cat', 02:18:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:32 INFO - 'PWD': '/builds/slave/test', 02:18:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:32 INFO - 'SHELL': '/bin/bash', 02:18:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXM4vkQy4K/Listeners', 02:18:32 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:18:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:32 INFO - 'USER': 'cltbld', 02:18:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:32 INFO - 'XPC_FLAGS': '0x0', 02:18:32 INFO - 'XPC_SERVICE_NAME': '0', 02:18:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:32 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:18:32 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 02:18:32 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 02:18:32 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 02:18:32 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 02:18:32 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 02:18:32 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 02:18:35 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:18:35 INFO - Installing collected packages: mozsystemmonitor 02:18:35 INFO - Running setup.py install for mozsystemmonitor 02:18:35 INFO - Successfully installed mozsystemmonitor 02:18:35 INFO - Cleaning up... 02:18:36 INFO - Return code: 0 02:18:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:18:36 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')]} 02:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:36 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')]} 02:18:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:36 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 0x1024bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe56a558730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10235ee00>, '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 0x1021c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102359b20>, '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.MBTaEIFiae/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.bXM4vkQy4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:18:36 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 02:18:36 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 02:18:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MBTaEIFiae/Render', 02:18:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:36 INFO - 'HOME': '/Users/cltbld', 02:18:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:36 INFO - 'LOGNAME': 'cltbld', 02:18:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:36 INFO - 'MOZ_NO_REMOTE': '1', 02:18:36 INFO - 'NO_EM_RESTART': '1', 02:18:36 INFO - 'PAGER': '/bin/cat', 02:18:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:36 INFO - 'PWD': '/builds/slave/test', 02:18:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:36 INFO - 'SHELL': '/bin/bash', 02:18:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXM4vkQy4K/Listeners', 02:18:36 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:18:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:36 INFO - 'USER': 'cltbld', 02:18:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:36 INFO - 'XPC_FLAGS': '0x0', 02:18:36 INFO - 'XPC_SERVICE_NAME': '0', 02:18:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:36 INFO - Downloading/unpacking blobuploader==1.2.4 02:18:36 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 02:18:36 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 02:18:36 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 02:18:36 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 02:18:36 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 02:18:36 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 02:18:38 INFO - Downloading blobuploader-1.2.4.tar.gz 02:18:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:18:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:18:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:18:39 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 02:18:39 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 02:18:39 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 02:18:39 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 02:18:39 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 02:18:39 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 02:18:39 INFO - Downloading docopt-0.6.1.tar.gz 02:18:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:18:39 INFO - Installing collected packages: blobuploader, requests, docopt 02:18:39 INFO - Running setup.py install for blobuploader 02:18:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:18:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:18:40 INFO - Running setup.py install for requests 02:18:40 INFO - Running setup.py install for docopt 02:18:40 INFO - Successfully installed blobuploader requests docopt 02:18:40 INFO - Cleaning up... 02:18:40 INFO - Return code: 0 02:18:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02: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')]} 02:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02: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')]} 02:18:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:40 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 0x1024bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe56a558730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10235ee00>, '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 0x1021c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102359b20>, '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.MBTaEIFiae/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.bXM4vkQy4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:18:40 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 02:18:40 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 02:18:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MBTaEIFiae/Render', 02:18:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:40 INFO - 'HOME': '/Users/cltbld', 02:18:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:40 INFO - 'LOGNAME': 'cltbld', 02:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:40 INFO - 'MOZ_NO_REMOTE': '1', 02:18:40 INFO - 'NO_EM_RESTART': '1', 02:18:40 INFO - 'PAGER': '/bin/cat', 02:18:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:40 INFO - 'PWD': '/builds/slave/test', 02:18:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:40 INFO - 'SHELL': '/bin/bash', 02:18:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXM4vkQy4K/Listeners', 02:18:40 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:18:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:40 INFO - 'USER': 'cltbld', 02:18:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:40 INFO - 'XPC_FLAGS': '0x0', 02:18:40 INFO - 'XPC_SERVICE_NAME': '0', 02:18:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:18:41 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-XpNHkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:18:41 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-IF1dJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:18:41 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-SmAqDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:18:41 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-CXsPiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:18:41 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-BU4Hci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:18:41 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Pe4FW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:18:41 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-lvs5QI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:18:41 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Y3LzWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:18:41 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-CBPkhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:18:42 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-uYsk3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:18:42 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-cwxMQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:18:42 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-b72bPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:18:42 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-nJhM8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:18:42 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-_z8uUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:42 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-PxrnPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:18:42 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-P1jAmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:18:42 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-f093hD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:18:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:18:42 INFO - Running setup.py install for manifestparser 02:18:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:18:43 INFO - Running setup.py install for mozcrash 02:18:43 INFO - Running setup.py install for mozdebug 02:18:43 INFO - Running setup.py install for mozdevice 02:18:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:18:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:18:43 INFO - Running setup.py install for mozfile 02:18:43 INFO - Running setup.py install for mozhttpd 02:18:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:18:43 INFO - Running setup.py install for mozinfo 02:18:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:18:43 INFO - Running setup.py install for mozInstall 02:18:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:18:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:18:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:18:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:18:44 INFO - Running setup.py install for mozleak 02:18:44 INFO - Running setup.py install for mozlog 02:18:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:18:44 INFO - Running setup.py install for moznetwork 02:18:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:18:44 INFO - Running setup.py install for mozprocess 02:18:44 INFO - Running setup.py install for mozprofile 02:18:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:18:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:18:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:18:44 INFO - Running setup.py install for mozrunner 02:18:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:18:44 INFO - Running setup.py install for mozscreenshot 02:18:45 INFO - Running setup.py install for moztest 02:18:45 INFO - Running setup.py install for mozversion 02:18:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:18:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:18:45 INFO - Cleaning up... 02:18:45 INFO - Return code: 0 02:18:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02: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')]} 02:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02: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')]} 02:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:45 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 0x1024bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe56a558730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10235ee00>, '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 0x1021c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102359b20>, '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.MBTaEIFiae/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.bXM4vkQy4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:18:45 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 02:18:45 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 02:18:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MBTaEIFiae/Render', 02:18:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:45 INFO - 'HOME': '/Users/cltbld', 02:18:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:45 INFO - 'LOGNAME': 'cltbld', 02:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:45 INFO - 'MOZ_NO_REMOTE': '1', 02:18:45 INFO - 'NO_EM_RESTART': '1', 02:18:45 INFO - 'PAGER': '/bin/cat', 02:18:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:45 INFO - 'PWD': '/builds/slave/test', 02:18:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:45 INFO - 'SHELL': '/bin/bash', 02:18:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXM4vkQy4K/Listeners', 02:18:45 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:18:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:45 INFO - 'USER': 'cltbld', 02:18:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:45 INFO - 'XPC_FLAGS': '0x0', 02:18:45 INFO - 'XPC_SERVICE_NAME': '0', 02:18:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:18:45 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-SWomFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:18:45 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)) 02:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:18:45 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-HFUdbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:18:45 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)) 02:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:18:45 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-8Uod_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:18:46 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)) 02:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:18:46 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-DCKzNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:18:46 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)) 02:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:18:46 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-YbuGhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:18:46 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)) 02:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:18:46 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-T1Bl60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:18:46 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)) 02:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:18:46 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-cUGMga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:18:46 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)) 02:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:18:46 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-gseR0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:18:46 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)) 02:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:18:46 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-TY3jup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:18:46 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)) 02:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:18:46 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-A9tUDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:18:46 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)) 02:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:18:46 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-4nvQOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:18:46 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)) 02:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:18:46 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Ww0pMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:18:46 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)) 02:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:18:46 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-MJ0g7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:18:47 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)) 02:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:18:47 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-2SPVbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:18:47 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)) 02:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:47 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-FDJa8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:47 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)) 02:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:18:47 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-8jdP_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:18:47 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)) 02:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:18:47 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-AtKhw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:18:47 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)) 02:18:47 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)) 02:18:47 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)) 02:18:47 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)) 02:18:47 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)) 02:18:47 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)) 02:18:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:18:47 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 02:18:47 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 02:18:47 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 02:18:47 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 02:18:47 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 02:18:47 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 02:18:50 INFO - Downloading blessings-1.6.tar.gz 02:18:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:18:50 INFO - Installing collected packages: blessings 02:18:50 INFO - Running setup.py install for blessings 02:18:50 INFO - Successfully installed blessings 02:18:50 INFO - Cleaning up... 02:18:50 INFO - Return code: 0 02:18:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:18:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:18:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:18:50 INFO - Reading from file tmpfile_stdout 02:18:50 INFO - Current package versions: 02:18:50 INFO - blessings == 1.6 02:18:50 INFO - blobuploader == 1.2.4 02:18:50 INFO - docopt == 0.6.1 02:18:50 INFO - manifestparser == 1.1 02:18:50 INFO - mozInstall == 1.12 02:18:50 INFO - mozcrash == 0.17 02:18:50 INFO - mozdebug == 0.1 02:18:50 INFO - mozdevice == 0.48 02:18:50 INFO - mozfile == 1.2 02:18:50 INFO - mozhttpd == 0.7 02:18:50 INFO - mozinfo == 0.9 02:18:50 INFO - mozleak == 0.1 02:18:50 INFO - mozlog == 3.1 02:18:50 INFO - moznetwork == 0.27 02:18:50 INFO - mozprocess == 0.22 02:18:50 INFO - mozprofile == 0.28 02:18:50 INFO - mozrunner == 6.11 02:18:50 INFO - mozscreenshot == 0.1 02:18:50 INFO - mozsystemmonitor == 0.0 02:18:50 INFO - moztest == 0.7 02:18:50 INFO - mozversion == 1.4 02:18:50 INFO - psutil == 3.1.1 02:18:50 INFO - requests == 1.2.3 02:18:50 INFO - wsgiref == 0.1.2 02:18:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02: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')]} 02:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02: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')]} 02:18:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:50 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 0x1024bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe56a558730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10235ee00>, '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 0x1021c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102359b20>, '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.MBTaEIFiae/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.bXM4vkQy4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:18:50 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 02:18:50 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 02:18:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MBTaEIFiae/Render', 02:18:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:50 INFO - 'HOME': '/Users/cltbld', 02:18:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:50 INFO - 'LOGNAME': 'cltbld', 02:18:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:50 INFO - 'MOZ_NO_REMOTE': '1', 02:18:50 INFO - 'NO_EM_RESTART': '1', 02:18:50 INFO - 'PAGER': '/bin/cat', 02:18:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:50 INFO - 'PWD': '/builds/slave/test', 02:18:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:50 INFO - 'SHELL': '/bin/bash', 02:18:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXM4vkQy4K/Listeners', 02:18:50 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:18:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:50 INFO - 'USER': 'cltbld', 02:18:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:50 INFO - 'XPC_FLAGS': '0x0', 02:18:50 INFO - 'XPC_SERVICE_NAME': '0', 02:18:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:51 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)) 02:18:51 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)) 02:18:51 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)) 02:18:51 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)) 02:18:51 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)) 02:18:51 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)) 02:18:51 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)) 02:18:51 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)) 02:18:51 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)) 02:18:51 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)) 02:18:51 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)) 02:18:51 INFO - Cleaning up... 02:18:51 INFO - Return code: 0 02:18:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:51 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 0x1024bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe56a558730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10235ee00>, '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 0x1021c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102359b20>, '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.MBTaEIFiae/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.bXM4vkQy4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:18:51 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 02:18:51 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 02:18:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MBTaEIFiae/Render', 02:18:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:51 INFO - 'HOME': '/Users/cltbld', 02:18:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:51 INFO - 'LOGNAME': 'cltbld', 02:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:51 INFO - 'MOZ_NO_REMOTE': '1', 02:18:51 INFO - 'NO_EM_RESTART': '1', 02:18:51 INFO - 'PAGER': '/bin/cat', 02:18:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:51 INFO - 'PWD': '/builds/slave/test', 02:18:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:51 INFO - 'SHELL': '/bin/bash', 02:18:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXM4vkQy4K/Listeners', 02:18:51 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:18:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:51 INFO - 'USER': 'cltbld', 02:18:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:51 INFO - 'XPC_FLAGS': '0x0', 02:18:51 INFO - 'XPC_SERVICE_NAME': '0', 02:18:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:51 INFO - Downloading/unpacking jsonschema 02:18:51 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 02:18:51 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 02:18:51 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 02:18:51 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 02:18:51 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 02:18:51 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 02:18:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:18:53 INFO - Downloading/unpacking functools32 (from jsonschema) 02:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:54 INFO - Downloading functools32-3.2.3-2.tar.gz 02:18:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:18:54 INFO - warning: no files found matching '*.txt' 02:18:54 INFO - no previously-included directories found matching 'build' 02:18:54 INFO - no previously-included directories found matching 'dist' 02:18:54 INFO - no previously-included directories found matching '.git*' 02:18:54 INFO - Installing collected packages: jsonschema, functools32 02:18:54 INFO - Running setup.py install for functools32 02:18:54 INFO - warning: no files found matching '*.txt' 02:18:54 INFO - no previously-included directories found matching 'build' 02:18:54 INFO - no previously-included directories found matching 'dist' 02:18:54 INFO - no previously-included directories found matching '.git*' 02:18:54 INFO - Successfully installed jsonschema functools32 02:18:54 INFO - Cleaning up... 02:18:54 INFO - Return code: 0 02:18:54 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:18:54 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')]} 02:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:54 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')]} 02:18:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:54 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 0x1024bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe56a558730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10235ee00>, '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 0x1021c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102359b20>, '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.MBTaEIFiae/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.bXM4vkQy4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:18:54 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 02:18:54 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 02:18:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MBTaEIFiae/Render', 02:18:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:54 INFO - 'HOME': '/Users/cltbld', 02:18:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:54 INFO - 'LOGNAME': 'cltbld', 02:18:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:54 INFO - 'MOZ_NO_REMOTE': '1', 02:18:54 INFO - 'NO_EM_RESTART': '1', 02:18:54 INFO - 'PAGER': '/bin/cat', 02:18:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:54 INFO - 'PWD': '/builds/slave/test', 02:18:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:54 INFO - 'SHELL': '/bin/bash', 02:18:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXM4vkQy4K/Listeners', 02:18:54 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:18:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:54 INFO - 'USER': 'cltbld', 02:18:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:54 INFO - 'XPC_FLAGS': '0x0', 02:18:54 INFO - 'XPC_SERVICE_NAME': '0', 02:18:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:54 INFO - Downloading/unpacking flake8 02:18:54 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 02:18:54 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 02:18:54 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 02:18:54 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 02:18:54 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 02:18:54 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 02:18:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:18:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:18:57 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 02:18:57 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 02:18:57 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 02:18:57 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 02:18:57 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 02:18:57 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 02:18:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:18:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:18:57 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 02:18:57 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 02:18:57 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 02:18:57 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 02:18:57 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 02:18:57 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 02:18:58 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:18:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:18:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:18:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:18:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:18:58 INFO - no previously-included directories found matching 'docs/_build' 02:18:58 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:18:58 INFO - Running setup.py install for pep8 02:18:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:18:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:18:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:18:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:18:58 INFO - no previously-included directories found matching 'docs/_build' 02:18:58 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:18:58 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:18:58 INFO - Cleaning up... 02:18:59 INFO - Return code: 0 02:18:59 INFO - Running post-action listener: _resource_record_post_action 02:18:59 INFO - Running post-action listener: _start_resource_monitoring 02:18:59 INFO - Starting resource monitoring. 02:18:59 INFO - ##### 02:18:59 INFO - ##### Running install step. 02:18:59 INFO - ##### 02:18:59 INFO - Running pre-action listener: _resource_record_pre_action 02:18:59 INFO - Running main action method: install 02:18:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:18:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:18:59 INFO - Reading from file tmpfile_stdout 02:18:59 INFO - Detecting whether we're running mozinstall >=1.0... 02:18:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:18:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:18:59 INFO - Reading from file tmpfile_stdout 02:18:59 INFO - Output received: 02:18:59 INFO - Usage: mozinstall [options] installer 02:18:59 INFO - Options: 02:18:59 INFO - -h, --help show this help message and exit 02:18:59 INFO - -d DEST, --destination=DEST 02:18:59 INFO - Directory to install application into. [default: 02:18:59 INFO - "/builds/slave/test"] 02:18:59 INFO - --app=APP Application being installed. [default: firefox] 02:18:59 INFO - mkdir: /builds/slave/test/build/application 02:18:59 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'] 02:18:59 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 02:19:31 INFO - Reading from file tmpfile_stdout 02:19:31 INFO - Output received: 02:19:31 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:19:31 INFO - Running post-action listener: _resource_record_post_action 02:19:31 INFO - ##### 02:19:31 INFO - ##### Running run-tests step. 02:19:31 INFO - ##### 02:19:31 INFO - Running pre-action listener: _resource_record_pre_action 02:19:31 INFO - Running main action method: run_tests 02:19:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:19:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:19:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:19:31 INFO - 2016-04-29 02:19:31.393 screenresolution[1647:7498] starting screenresolution argv=screenresolution get 02:19:31 INFO - 2016-04-29 02:19:31.396 screenresolution[1647:7498] Display 0: 1600x1200x32@60 02:19:31 INFO - 2016-04-29 02:19:31.401 screenresolution[1648:7500] starting screenresolution argv=screenresolution list 02:19:31 INFO - Available Modes on Display 0 02:19:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:19:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:19:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:19:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:19:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:19:31 INFO - Intel Iris: 02:19:31 INFO - Chipset Model: Intel Iris 02:19:31 INFO - Type: GPU 02:19:31 INFO - Bus: Built-In 02:19:31 INFO - VRAM (Dynamic, Max): 1536 MB 02:19:31 INFO - Vendor: Intel (0x8086) 02:19:31 INFO - Device ID: 0x0a2e 02:19:31 INFO - Revision ID: 0x0009 02:19:31 INFO - Displays: 02:19:31 INFO - Display: 02:19:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:19:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:19:31 INFO - Display Serial Number: 1600x1200 60 02:19:31 INFO - Main Display: Yes 02:19:31 INFO - Mirror: Off 02:19:31 INFO - Online: Yes 02:19:31 INFO - Rotation: Supported 02:19:31 INFO - Return code: 0 02:19:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:19:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:19:31 INFO - Python 2.7.3 02:19:31 INFO - Return code: 0 02:19:31 INFO - grabbing minidump binary from tooltool 02: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')]} 02:19:31 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10235ee00>, '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 0x1021c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102359b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:19:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:19:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:19:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:19:31 INFO - Return code: 0 02:19:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:19:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:19:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:19:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:19:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:19:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:19:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:19:32 INFO - Return code: 0 02:19:32 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/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0143', '--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 02:19:32 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/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0143 --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 02:19:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MBTaEIFiae/Render', 02:19:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:32 INFO - 'HOME': '/Users/cltbld', 02:19:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:32 INFO - 'LOGNAME': 'cltbld', 02:19:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:19:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:32 INFO - 'MOZ_NO_REMOTE': '1', 02:19:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:19:32 INFO - 'NO_EM_RESTART': '1', 02:19:32 INFO - 'PAGER': '/bin/cat', 02:19:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:32 INFO - 'PWD': '/builds/slave/test', 02:19:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:19:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:32 INFO - 'SHELL': '/bin/bash', 02:19:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXM4vkQy4K/Listeners', 02:19:32 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:19:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:32 INFO - 'USER': 'cltbld', 02:19:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:32 INFO - 'XPC_FLAGS': '0x0', 02:19:32 INFO - 'XPC_SERVICE_NAME': '0', 02:19:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:32 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/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0143', '--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 02:19:33 INFO - mozversion application_buildid: 20160429004052 02:19:33 INFO - mozversion application_changeset: 6e453fe828bf9054b6db0307ffb034d3acceb9c9 02:19:33 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:19:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:19:33 INFO - mozversion application_name: Firefox 02:19:33 INFO - mozversion application_remotingname: firefox-dev 02:19:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:19:33 INFO - mozversion application_vendor: Mozilla 02:19:33 INFO - mozversion application_version: 48.0a2 02:19:33 INFO - mozversion platform_buildid: 20160429004052 02:19:33 INFO - mozversion platform_changeset: 6e453fe828bf9054b6db0307ffb034d3acceb9c9 02:19:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:19:33 INFO - mozversion platform_version: 48.0a2 02:19:33 INFO - using testdate: 1461921573 02:19:33 INFO - actual date: 1461921573 02:19:33 INFO - starting webserver on 'localhost:49273' 02:19:33 INFO - SUITE-START | Running 2 tests 02:19:33 INFO - TEST-START | dromaeo_css 02:19:33 INFO - operating with platform_type : mac_ 02:19:33 INFO - Initialising browser for dromaeo_css test... 02:19:33 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpH6KdcM/profile http://localhost:49273/getInfo.html) 02:19:35 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 02:19:35 INFO - PROCESS | 1666 | colorDepth:24 02:19:35 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 02:19:35 INFO - PROCESS | 1666 | __metrics 02:19:35 INFO - PROCESS | 1666 | 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" 02:19:35 ERROR - PROCESS | 1666 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:19:36 INFO - TEST-INFO | 1666: exit 0 02:19:36 INFO - Browser initialized. 02:19:36 INFO - Running cycle 1/1 for dromaeo_css test... 02:19:36 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpH6KdcM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:19:48 INFO - PROCESS | 1669 | RSS: Main: 211431424 02:19:48 INFO - PROCESS | 1669 | 02:20:39 INFO - PROCESS | 1669 | 1461921639777 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] 02:22:50 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 02:22:51 INFO - PROCESS | 1669 | RSS: Main: 364228608 02:22:51 INFO - PROCESS | 1669 | 02:25:54 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 02:25:55 INFO - PROCESS | 1669 | RSS: Main: 353275904 02:25:55 INFO - PROCESS | 1669 | 02:28:57 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 02:28:58 INFO - PROCESS | 1669 | RSS: Main: 356716544 02:28:58 INFO - PROCESS | 1669 | 02:32:00 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 02:32:00 INFO - PROCESS | 1669 | RSS: Main: 354811904 02:32:00 INFO - PROCESS | 1669 | 02:35:04 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 02:35:04 INFO - PROCESS | 1669 | RSS: Main: 327917568 02:35:04 INFO - PROCESS | 1669 | 02:38:07 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 02:38:07 INFO - PROCESS | 1669 | RSS: Main: 361070592 02:38:07 INFO - PROCESS | 1669 | 02:38:07 INFO - PROCESS | 1669 | __start_tp_report 02:38:07 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 02:38:07 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 02:38:07 INFO - PROCESS | 1669 | |i|pagename|runs| 02:38:07 INFO - PROCESS | 1669 | |0;dojo.html;3351.530785690003;3442.019024577994;3449.9655003449966;3472.756907016507;3510.842012109455;5688.60179787415;5698.002849501331;5700.429957004303;5710.685912274823;5736.483716465517;5670.716464176795;5714.314282286121;5723.399043100477;5728.570357223204;5762.250907382045;5044.545990860822;5102.030614183293;5137.409197942228;5159.097157997345;5175.223716442526;5183.118869792144;5196.636235463519;5203.245529398235;5206.869828254286;5207.4792520748;5128.948710512885;5169.198774190007;5178.326817513718;5186.273921650972;5226.895462090756;4524.914020635057;5196.974015129938;5246.606504512177;5260.500252476008;5291.1798671206025;5039.546440820307;5074.238864170404;5093.108705976476;5154.974225128888;5177.404598471148;5505.229267902497;5548.91676624851;5552.111662134039;5577.358603760579;5591.776328946838;6171.074338849164;6190.071489276599;6193.0710393440895;6201.472874805647;6204.162438070893;6058.727357268934;6117.066276512079;6156.87686246277;6179.752809887599;6191.907121393183;5735.598508104393;5765.106408506688;5771.249737534177;5789.392113828029;5810.33181184165;6261.843453913598;6262.655553944577;6268.404501572343;6272.1219029335925;6272.278687950853;5904.852378690482;6019.976603977334;6032.276126864805;6100.3899610039525;6111.5110791137495;6228.128062071313;6240.906386404207;6245.875082498324;6252.3122456529745;6271.059341098871;6096.146539484475;6151.292601350812;6171.6297022178815;6187.969060154671;6192.504599632019;6048.697565121726;6108.827782940854;6118.923762351576;6168.599041062406;6193.256809182927;4834.419869615669;4849.127157111683;4859.854204373874;4867;4873.829415970424;4351.673624478177;4381.868543943687;4401.361802538614;4433.135538569948;4457.509673935864;5608.887822243532;5636.689982051026;5646.491815736604;5652.576056795757;5660.037793575102;5218.843434696965;6203.038529027917;6253.74985000604;6262.718177682015;6267;5471.72641367938;5544.91682624761;5575.414581468968;5637.943620563824;5653.886922261532;5279.498447647467;5295.099833028395;5304.389995150607;5309.548688361455;5330.7068111253475;6251.0623406488485;6292.779752708633;6294.464970477561;6299.937000629935;6301.991681330965;6023.66869822164;6124.564025909317;6126.517673797367;6133.334663918993;6142.283918229586;5156.5616922562;6239.875202496016;6279.026750853625;6307.716152773044;6307.936920630735;6118.816435506942;6125.724342404514;6157.630542167484;6160.7535698571555;6167.167432396748;6181.474574661204;6196.225471816023;6227.408396202353;6270.6551139685935;6290.370962903673;5900.380460051632;6016.939830601638;6037.698115094316;6134.04922237054;6137.539684523589;6057.394260573908;6069.968105422001;6072.392760724069;6078.54410919174;6094.2077529920825;5812.505936995402;5883.970580147072;5895.20414744004;5910.527157827278;5911.586188966731;5869.06095024794;5954.4343287389365;5956.434138756811;6084.665343405978;6094.360092190256;5879.147523609138;5900.232969713909;5913.526917846648;5924.6149000314845;5926.022206336079 02:38:07 INFO - PROCESS | 1669 | |1;ext.html;510.31108004194334;512.1344927073249;581.828360633613;590.9497692696121;626.8432891777056;29191.416171676552;30705.23236919078;38121.23757524851;39416.80291598545;39444.80277598608;40352.789416317486;41543;43746.56253437464;44405.77797111018;44526.55473445264;9522.000189980055;9730.367526110815;9731.708048758548;9743.95128024359;9745.415275083518;8303.46027508213;8546.914530854705;11123.1657625678;11409.885901141008;11412.828807567894;11409.714757131096;11556.364399957998;11582.6525204244;11599.884001160008;11600.303981761079;8325.333973282122;11150;11457.427128643576;11642.941785291105;11645.18483706141;11272.774544509146;11426.485808138654;11426.71433214168;11437.542498300058;11465.254758440675;37715;38116.809415952746;38822.80588597039;38829.611703883165;38836.41745373821;3225.4194245035883;3389.932201355959;3646.47126166707;3787.6401741834484;3828;28103.297417564725;28682.569761453597;28829.135125946254;29044.12867613975;29112.981045663513;2708.776615623804;2734.1387462949306;2753.9449211015667;2764.9861750691116;2768.5847122931723;484491;485007;485248;486094;486589;61904.69047654733;62255;62328.688356558836;62339.6883015582;62575.06137407943;63323.36676633267;64728.67635661886;64755.676221619535;64780.35219647837;64811;14633.560993170222;17134;17228.483145505656;17340.566485837953;17372.739408908878;11886.049116070693;12161.756764864653;12174.878251217551;13202.73594528104;13305.334733263298;17268.65462690739;17523.73714394285;18787.34244301443;19234.326798561982;19247.51881202981;7245.492815502969;7884.921150788534;7959.84080318402;8225.259726624632;8297.087320394752;815.604431850552;985.7732721473958;1091.9563217471211;1100.3068067117665;1117.3296022386503;25552;29843;29894.85052574723;30394.696053039628;30561.541576876363;768.3353898877542;1113.4822307626991;1121.8149005414016;1125.6791814332876;1142.0749193153572;14633.707325853424;15003;15169.848301517064;15177.317020734094;15521.844781552265;665004;676375;676820;676915;677942;59544.40455595475;61297.38702613006;61336.386636133066;61433;61525.384746152864;1332.4936524120965;1358.8369395672385;1360.149906308557;1417.1567917089315;1520.1107352199087;23229.3031209064;30008.69991300059;30165.396692066475;30552.388952221274;30592.84703576468;54100.729496352265;54441;54657.453425466825;54701;54701;481176;495828;497016;497079;498022;22225.110995560644;24616.6307505384;25315.87342063278;26762.063327783435;27177.72822271752;41997;42449.57550424456;43879.78060109679;44045;44064.67805965826;35206.59173633026;35678.643213567535;35870;35988.83958652548;36706.8164659175;34219;36637.63362366342;36872;37477.063073423386;37481.437778433894;36379.636203638685;36950.07624809401;37010;37232.06919827026;37555.62444375631;31482.685173147976;32656.51015234726;32656.83671581627;32673.18317042093;33716.49425258672;24297;24561;24614.630780537947;24992.50014999726;25083.372915677253 02:38:07 INFO - PROCESS | 1669 | |2;jquery.html;393.341153567774;396.87696813987645;398.73085667174666;398.8344836892689;408.8099033949213;13942.442302307922;19789.50526236845;20726.585468290625;20732.89633551832;20830.687539686878;16180.029198248083;17616.03111828849;19822.80177198231;20453;20691.689624655606;5734.197212390269;5910.674912879811;5957.582969192136;5964.552658550604;5967.850803729898;5862.794802181925;5886;5898.7640494380175;5899.32157801854;5920.111983202511;5615.241942337793;5798.681072541009;5829.067349224125;5834.445727655866;5835.649861008353;5808.709564521778;5876.9118463223085;5879.559033072515;5890.322612899528;5894.263217097863;5002.449730529639;5163;5179.456157103526;5183.222516622499;5188.7146206958605;24114;24819.62770558443;25487.617685734727;25521;26127.73872261272;2177.1182671018264;2208.2823082498094;2242.2264318809985;2417.8065754739578;2874.1090262018633;13333.866661333457;14281.21453320067;14375.353109110116;14601.488947886774;15092.924535377362;1593.6015996001;1670.156570931684;1703.3442124781925;1772.929082836685;1805.1876655505075;107852;107976;110561.44719276433;111165.99999999919;114014;10397.84403233952;10684.732881677864;10964.807038592313;10965.61620343284;11045.889541104487;13738.381772820247;13768.518101866586;13800;13804.309784510735;13821.723565528835;7466.477346585687;7624.123225829101;8596.613152408157;8696.434731742416;8702.47785132894;8661.740147795575;8667.306615470747;8682.34882383811;8795.208431241219;8797.51613661242;6220.129181914535;6355.459785918156;6403.903941440882;6419.614823110628;6446.484281257488;1819.699749541311;1846.3537761783268;1847.7136043913215;1850.2136591948367;1888.6222755448691;962.2302158273353;970.7281961050917;1053.7629033467408;1064.2762921213493;1093.9671809845718;19747.703784443245;20311.390658280274;20727.170913163303;20747.377578672666;20769.896150519453;998.0119681515248;1058.216919479595;1066.2163309967168;1071.6604244694133;1117.608836907076;9437.622495100119;9895.554700038516;10823.567057317776;11090;11111.111111111091;627528;633021;635033;648368;649000;108975.45512272547;110836;112282.43858780654;119186.40406798084;121948.39025804991;1024.3085917005992;1041.6979076067855;1058.7882423515327;1060.8408738689259;1072.3780207479642;17780.199891005195;17825.197866095798;17948;18363.908180459013;18686.43940681782;37700.62299376971;48556.54330370094;49147.26279105888;49547.50452495429;49739.75650608764;67297;68054.65972670105;68682;69106.65446672734;70641.64679176571;7676.462647614837;7826.2173782621285;7887.684492620231;7920.881186782318;8235.094139644514;12131.393430328624;12295.200811947196;12439.191452555555;12717.10980231375;12728.554500592434;10787.676369708608;10917.017468427879;10965.561377545127;11067.72330691739;11109.388983605979;10454.738631534272;10498.47507624631;10754.462276886281;10953.014228719454;10989.725256868642;8837.867431988398;9591.712248632552;11085.667429977113;11116.22186446941;11547.769044619228;7017.4386049116965;7565.243475652611;7874.488158269694;7907.327877130509;7945.8808117879435;10838.891611084104;10870.293430926964;11134.944325278322;11210.55157793679;11220.934011268917 02:38:07 INFO - PROCESS | 1669 | |3;mootools.html;1406.2336026865357;1591.4589039726495;1600.6478574713562;1821.808710085441;1944.1057113727684;7825.2174782521715;8783.648654053839;8982.730518084452;8986.370954033204;9003.549822508865;8594.398392112538;8777;8777.297816174707;8816.559172041389;8820.20618144367;2433.7201221859536;2468.950620987579;2470.654108424822;2472.258322503251;2472.6538284640164;2366.372911178539;2417.7340492545804;2417.842840215389;2426.4783071639595;2429.2833614083797;2421.673074134987;2443.535728211643;2449.742777008415;2461.310832966772;2463.9753602464016;2393.796527295182;2422.0432928993127;2431.610942249232;2434.294054724128;2435.4520232947534;2389.5459862626044;2406.446517301013;2416.7704068113503;2418.1657328221913;2421.6004359280696;8665.09016553259;8895.4662720237;8945.373823832335;8948.239399651038;8962.14859588338;5239.79040838366;5454.209139674765;5496.972515137399;5512.531434828044;5523.420040895729;3458.8789392371145;3498.5276987606603;3572.696320812708;3649.6897763690117;3656.8171591420587;1020.3163880200209;1046.7173863057008;1050.4852622215135;1053.0995998421315;1068.305601359115;11715.7071073223;11832.881671183264;11870.990965768007;11874.109441791814;11877.762444751143;4571.885702857388;4588.472325682523;4685.6017238535105;4718.905621887612;4725.8582242532775;9208.171264586086;9228;9229.953850230706;9233.584488698025;9240.907590924138;5232.73836308183;5331.840044798662;5341.439148889388;5352.2506849041165;5356.116240820299;3762.9087564606507;4482.058767658763;4638.744869032168;4981.626378021663;5014.498550145029;3797.1456422305314;3862.4592557042038;3907.9609203908167;3925.0776067624083;3952.8418863245147;1200.6458094862037;1202.6812894582943;1203.5426537915537;1203.9699007524707;1208.5738828182828;830.2776584371635;951.7763325618474;963.7590602349412;970.7767213540925;984.7341217871278;5784.88430231393;5994.640321580632;6024.728887200086;6044.425779550936;6219.782307619212;466.2656316301838;471.5166953872308;481.57380718064064;484.1648156929339;484.4211167654619;3800.619938006177;4027.637512623878;4028.7784171870244;4064.9187016260103;4104.958950410458;10300.896991030144;10647;10929.961653642891;10930.180236482296;11011.174161937726;10349.534270957825;10680.198985076151;10691.893081069089;10841.891581084088;10972.890271097347;6512.934870651327;8006.039275287003;8237.670493180205;8245.83508329842;8423.862778524943;5802.970985145047;6100.206973093469;6175.104609831459;6176.042713379434;6224.782132625336;9478.289128315266;10138.290319677846;10143.7464063399;10199.439030853078;10299.3820370778;9899.50502474859;10131;10164;10340.638077667245;10361.896381036395;6495.894713822854;6815.284395138439;7343.4492413068165;7579.242075792376;7585.848283034419;8249.463784853966;8253;8259.710910118118;8337.499750015018;8356.331493480386;7320.524165929198;7407.51849630087;7413.9258607415395;7484.625768711651;7493.4379921505015;7132.786016419515;7203.927960720535;7313.451491138292;7332.890006650011;7349.30181632744;7040.471964602778;7092;7141.214466408795;7141.857162856818;7158.570485770871;4835.20219163834;4927.18701414277;4943.110240156662;5051;5064.797408103782;4882.218844984927;4937.185364414975;4945.851624451272;4993.700377977333;5005.649604527648 02:38:07 INFO - PROCESS | 1669 | |4;prototype.html;313.5657114895869;316.67382595926193;322.7289077175171;325.6710722170607;327.6166884744848;4858.927116093257;5245.685258884465;5281.837995640963;5294.841154765353;5309.50453286955;4331.45856767904;4391.6706247031525;5141.562967147787;5190.922136167951;5202.8179013734525;3635.636436356363;3861.4980052593264;3951.308521008812;3977.98011009946;4038.6567141792984;3975.4633124528254;4026.4765580474495;4032.5967403259733;4035.132446523997;4075.332201019077;3794.924101517968;3971.7418367806;3978.860739874105;3984.1434091670285;3989.880303590897;3835.5397352317623;3857.0164608024916;4019.733783858075;4034.4755181826467;4046.979765101156;3385.356782211372;3542.6988705960025;3544.8936531904083;3557.314227758052;3564.518789963348;4608.861734147981;5006.774695138691;5042.890564075898;5055.266986286968;5066.265391518246;3923.5684074751753;3954.5649978502342;4419.558044195587;4603.49361570227;4660.533946605312;5468.6171967962255;5495.395506494282;5501.4223506532035;5513.255710479074;5521.475459831309;3600.1539638185004;3702.9259414811822;3915.57081665193;3925.410208865414;3960.960390396117;5469.808556700536;5765.827025189251;5812.111063904248;5812.733034116698;5835.183074369592;3213.6984521268923;3818.446325282874;3960.108975480494;3960.405939109157;3982.2234664240195;3638.308721342936;4034.5561988181275;4045.8583949561626;4050.3316952702476;4063.715539912237;4263.978680106579;4376;4601.28680054592;4601.74690392025;4636.188666983265;4396.493998420933;5225.921611175835;5539.224508568804;5546.69493177871;5558.527525160407;1261.564760157744;1319.0041518653354;1433.5770947570493;1453.3096779030088;1486.9033512821632;921.1709461484718;929.2380248196414;957.9377340472847;982.8181786369402;1001.737810358953;4466.754328511963;5021.271915572294;5537.612367134262;5569.805056823073;5592.636478628876;6123.357047510036;6128.161551534522;6136.6318020918;6154.292256390483;6158.107074474176;5214.60890433219;5490.615656904059;5500.477454641803;5514.077538904387;5518.392976772552;2830.7593854522183;3208.4866421372467;3230.7415406767404;3234.5956755405573;3266.608007039123;5551.834114835849;6146.200993870768;6367.522435817332;6382.5532212744665;6396.84007899797;6213.285472170668;6236.750529978841;6258.026153392629;6298.401651843067;6316.62100273985;5645.548356131581;5728.112142617901;6399.232092148972;6400.167978162809;6424.807255782334;5391.4608539145775;5829.358770535323;5961.837441698827;6140.539459540463;6221;5283.996040752245;5833.854153646193;6045.455908968214;6079.969600151971;6083.6349819011;5677.375488696158;6110.791834287035;6386.680665966776;6400.807975760735;6442.774502892376;4211.684123690797;4217.894552636086;4232.851850185229;4236.95763042378;4277.893052673708;5897.911531326948;6126.468382904274;6126.816195514141;6127.387261273837;6149.446549810538;5208.661437006583;5567.220589117608;5568.860778480408;5576.219329293821;5979.4618484336615;4863.489333619919;5406.783473718382;5521.570508976038;5543.223948647112;5548.944510554843;4867.269909513601;5119.13328536021;5318.048483091921;5408.512659018801;5425.914817036687;4782.808687652455;5484.2047903053035;5558.555315574825;5560.972195138998;5595.804146854841;4906.748779061317;5259.289995850664;5454.754536045808;5455.509004189642;5467.1411720015385 02:38:07 INFO - PROCESS | 1669 | |5;yui.html;622.9875402491949;652.2075678051173;732.5283100937658;744.1145037405487;750.6584504050658;11641.301521908681;14106.012579119466;14552.272386380691;14634.926825365857;14792;13560.45758169672;13676.93161534191;13764.724705505909;13772.449102035907;13780.655483612889;729.8941653460245;752.9849403011937;866.0906048648925;888.2316795971502;923.0907556057278;910.9180173784358;913.954302284883;915.8214148241095;917.2661870503604;917.4220241248005;973.3721749471587;973.9366941148838;976.1897624971257;977.1694060048937;977.1791694976218;750.7897788619196;930.3301622831584;964.7781010367585;967.2842096848352;967.4920666649995;712.9251428599974;713.1905287498727;713.5575942915426;714.2928500784238;716.8172116110411;14605.6348591285;14644;14676.559703208814;14677.26613669323;14680.926595367062;12099;12262.816057759052;12384.504619815287;12480.875191248062;12854.357282135948;9887.851682224773;9973.601055957753;10179.491025448771;10369.688909332732;10490.58037678492;3417.8461969211444;3464;3516.1385460562324;3531.969385627913;3729.8881033569037;71640;73634.63182684052;73670;73717;73765;9311.627534898664;9322.487263200454;9346.392484488486;9425.622975081056;9623.855642165374;20973.895130524248;20976.580468390548;20985.950702464816;21022.789772102387;21045.158193672083;7045.8238544035785;7217.5308604941565;7296.7810965671115;7304.780856574312;7485.737999170003;3972.801359931992;3974.28462876685;4053.1285773558825;4117.608827161415;4126.649234815014;3100.9224769380494;3486.965130348664;3518.997085830526;3546.113471632092;3565.8038807865296;620.3269452643842;647.4917190005823;648.7794149989027;650.4926157597082;668.3316683316683;388.4360987935735;507.7841917185182;525.5637820608886;536.6136381805172;552.2379116818845;6714.83212919681;7211.387032102225;7647.923520764833;8072.515649061075;8076.555789431638;532.5739408473206;570.9978986878085;580.851882769891;602.0908428273339;613.1048668943304;3598.5321908151773;3668.486411902336;3779.244151169777;3909.9022524436546;3921.058945852975;68888.65555672189;83823.3235335302;88519;91135;91956.54021729849;60322;77187.61406192933;77248.61375693085;77872.44255114866;78068.75934230194;68784.31215687779;69413.65293173502;69752.30247697458;70376.64811676012;70799.64600176967;6703.865922681616;7469.365103966224;7544.66049027806;7706.036745406825;7739.18738532446;34719.4792078114;40932.38601421041;42487.787561061996;43179;43797;58222;58617;58704;59470.70264648649;60250.69874650774;10756.354618722902;10949.945250273699;10998.34009959405;11095.389753563632;11722.58970936013;10496.895031049591;11071.446427678746;11604.825927611262;11855.466504007169;12128.636140915787;10374.599429076978;11267.098632109248;11419.657410277705;11638.592649257236;11645.88354116448;9760.804783904425;11011.953864382871;11520.711982200512;11839.526418943145;12035.398230088636;10992.230543861853;11156.49795759209;11284.717882053013;11437.656870293904;11965.939871002485;9439.339246252697;9457.668981585612;9493.193078588398;9726.610935562498;9729;9493.430394176372;9600.135987761136;9762.36544624597;9820.312578119749;9833.16418104469 02:38:07 INFO - PROCESS | 1669 | __end_tp_report 02:38:07 INFO - PROCESS | 1669 | __start_cc_report 02:38:07 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1484 02:38:07 INFO - PROCESS | 1669 | __end_cc_report 02:38:07 INFO - PROCESS | 1669 | __startTimestamp1461922687948__endTimestamp 02:38:07 INFO - PROCESS | 1669 | 02:38:07 INFO - PROCESS | 1669 | ------- Summary: start ------- 02:38:07 INFO - PROCESS | 1669 | Number of tests: 6 02:38:07 INFO - PROCESS | 1669 | 02:38:07 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5686.27 Median:5900.23 stddev:628.71 (10.7%) stddev-sans-first:603.37 02:38:07 INFO - PROCESS | 1669 | Values: 3351.5 3442.0 3450.0 3472.8 3510.8 5688.6 5698.0 5700.4 5710.7 5736.5 5670.7 5714.3 5723.4 5728.6 5762.3 5044.5 5102.0 5137.4 5159.1 5175.2 5183.1 5196.6 5203.2 5206.9 5207.5 5128.9 5169.2 5178.3 5186.3 5226.9 4524.9 5197.0 5246.6 5260.5 5291.2 5039.5 5074.2 5093.1 5155.0 5177.4 5505.2 5548.9 5552.1 5577.4 5591.8 6171.1 6190.1 6193.1 6201.5 6204.2 6058.7 6117.1 6156.9 6179.8 6191.9 5735.6 5765.1 5771.2 5789.4 5810.3 6261.8 6262.7 6268.4 6272.1 6272.3 5904.9 6020.0 6032.3 6100.4 6111.5 6228.1 6240.9 6245.9 6252.3 6271.1 6096.1 6151.3 6171.6 6188.0 6192.5 6048.7 6108.8 6118.9 6168.6 6193.3 4834.4 4849.1 4859.9 4867.0 4873.8 4351.7 4381.9 4401.4 4433.1 4457.5 5608.9 5636.7 5646.5 5652.6 5660.0 5218.8 6203.0 6253.7 6262.7 6267.0 5471.7 5544.9 5575.4 5637.9 5653.9 5279.5 5295.1 5304.4 5309.5 5330.7 6251.1 6292.8 6294.5 6299.9 6302.0 6023.7 6124.6 6126.5 6133.3 6142.3 5156.6 6239.9 6279.0 6307.7 6307.9 6118.8 6125.7 6157.6 6160.8 6167.2 6181.5 6196.2 6227.4 6270.7 6290.4 5900.4 6016.9 6037.7 6134.0 6137.5 6057.4 6070.0 6072.4 6078.5 6094.2 5812.5 5884.0 5895.2 5910.5 5911.6 5869.1 5954.4 5956.4 6084.7 6094.4 5879.1 5900.2 5913.5 5924.6 5926.0 02:38:07 INFO - PROCESS | 1669 | 02:38:07 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:68609.50 Median:35774.32 stddev:149205.01 (417.1%) stddev-sans-first:149535.96 02:38:07 INFO - PROCESS | 1669 | Values: 510.3 512.1 581.8 590.9 626.8 29191.4 30705.2 38121.2 39416.8 39444.8 40352.8 41543.0 43746.6 44405.8 44526.6 9522.0 9730.4 9731.7 9744.0 9745.4 8303.5 8546.9 11123.2 11409.9 11412.8 11409.7 11556.4 11582.7 11599.9 11600.3 8325.3 11150.0 11457.4 11642.9 11645.2 11272.8 11426.5 11426.7 11437.5 11465.3 37715.0 38116.8 38822.8 38829.6 38836.4 3225.4 3389.9 3646.5 3787.6 3828.0 28103.3 28682.6 28829.1 29044.1 29113.0 2708.8 2734.1 2753.9 2765.0 2768.6 484491.0 485007.0 485248.0 486094.0 486589.0 61904.7 62255.0 62328.7 62339.7 62575.1 63323.4 64728.7 64755.7 64780.4 64811.0 14633.6 17134.0 17228.5 17340.6 17372.7 11886.0 12161.8 12174.9 13202.7 13305.3 17268.7 17523.7 18787.3 19234.3 19247.5 7245.5 7884.9 7959.8 8225.3 8297.1 815.6 985.8 1092.0 1100.3 1117.3 25552.0 29843.0 29894.9 30394.7 30561.5 768.3 1113.5 1121.8 1125.7 1142.1 14633.7 15003.0 15169.8 15177.3 15521.8 665004.0 676375.0 676820.0 676915.0 677942.0 59544.4 61297.4 61336.4 61433.0 61525.4 1332.5 1358.8 1360.1 1417.2 1520.1 23229.3 30008.7 30165.4 30552.4 30592.8 54100.7 54441.0 54657.5 54701.0 54701.0 481176.0 495828.0 497016.0 497079.0 498022.0 22225.1 24616.6 25315.9 26762.1 27177.7 41997.0 42449.6 43879.8 44045.0 44064.7 35206.6 35678.6 35870.0 35988.8 36706.8 34219.0 36637.6 36872.0 37477.1 37481.4 36379.6 36950.1 37010.0 37232.1 37555.6 31482.7 32656.5 32656.8 32673.2 33716.5 24297.0 24561.0 24614.6 24992.5 25083.4 02:38:07 INFO - PROCESS | 1669 | 02:38:07 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:35089.37 Median:20758.64 stddev:105663.06 (509.0%) stddev-sans-first:105927.35 02:38:07 INFO - PROCESS | 1669 | Values: 393.3 396.9 398.7 398.8 408.8 13942.4 19789.5 20726.6 20732.9 20830.7 16180.0 17616.0 19822.8 20453.0 20691.7 5734.2 5910.7 5957.6 5964.6 5967.9 5862.8 5886.0 5898.8 5899.3 5920.1 5615.2 5798.7 5829.1 5834.4 5835.6 5808.7 5876.9 5879.6 5890.3 5894.3 5002.4 5163.0 5179.5 5183.2 5188.7 24114.0 24819.6 25487.6 25521.0 26127.7 2177.1 2208.3 2242.2 2417.8 2874.1 13333.9 14281.2 14375.4 14601.5 15092.9 1593.6 1670.2 1703.3 1772.9 1805.2 107852.0 107976.0 110561.4 111166.0 114014.0 10397.8 10684.7 10964.8 10965.6 11045.9 13738.4 13768.5 13800.0 13804.3 13821.7 7466.5 7624.1 8596.6 8696.4 8702.5 8661.7 8667.3 8682.3 8795.2 8797.5 6220.1 6355.5 6403.9 6419.6 6446.5 1819.7 1846.4 1847.7 1850.2 1888.6 962.2 970.7 1053.8 1064.3 1094.0 19747.7 20311.4 20727.2 20747.4 20769.9 998.0 1058.2 1066.2 1071.7 1117.6 9437.6 9895.6 10823.6 11090.0 11111.1 627528.0 633021.0 635033.0 648368.0 649000.0 108975.5 110836.0 112282.4 119186.4 121948.4 1024.3 1041.7 1058.8 1060.8 1072.4 17780.2 17825.2 17948.0 18363.9 18686.4 37700.6 48556.5 49147.3 49547.5 49739.8 67297.0 68054.7 68682.0 69106.7 70641.6 7676.5 7826.2 7887.7 7920.9 8235.1 12131.4 12295.2 12439.2 12717.1 12728.6 10787.7 10917.0 10965.6 11067.7 11109.4 10454.7 10498.5 10754.5 10953.0 10989.7 8837.9 9591.7 11085.7 11116.2 11547.8 7017.4 7565.2 7874.5 7907.3 7945.9 10838.9 10870.3 11134.9 11210.6 11220.9 02:38:07 INFO - PROCESS | 1669 | 02:38:07 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5701.60 Median:484.29 stddev:3201.57 (661.1%) stddev-sans-first:3194.28 02:38:07 INFO - PROCESS | 1669 | Values: 1406.2 1591.5 1600.6 1821.8 1944.1 7825.2 8783.6 8982.7 8986.4 9003.5 8594.4 8777.0 8777.3 8816.6 8820.2 2433.7 2469.0 2470.7 2472.3 2472.7 2366.4 2417.7 2417.8 2426.5 2429.3 2421.7 2443.5 2449.7 2461.3 2464.0 2393.8 2422.0 2431.6 2434.3 2435.5 2389.5 2406.4 2416.8 2418.2 2421.6 8665.1 8895.5 8945.4 8948.2 8962.1 5239.8 5454.2 5497.0 5512.5 5523.4 3458.9 3498.5 3572.7 3649.7 3656.8 1020.3 1046.7 1050.5 1053.1 1068.3 11715.7 11832.9 11871.0 11874.1 11877.8 4571.9 4588.5 4685.6 4718.9 4725.9 9208.2 9228.0 9230.0 9233.6 9240.9 5232.7 5331.8 5341.4 5352.3 5356.1 3762.9 4482.1 4638.7 4981.6 5014.5 3797.1 3862.5 3908.0 3925.1 3952.8 1200.6 1202.7 1203.5 1204.0 1208.6 830.3 951.8 963.8 970.8 984.7 5784.9 5994.6 6024.7 6044.4 6219.8 466.3 471.5 481.6 484.2 484.4 3800.6 4027.6 4028.8 4064.9 4105.0 10300.9 10647.0 10930.0 10930.2 11011.2 10349.5 10680.2 10691.9 10841.9 10972.9 6512.9 8006.0 8237.7 8245.8 8423.9 5803.0 6100.2 6175.1 6176.0 6224.8 9478.3 10138.3 10143.7 10199.4 10299.4 9899.5 10131.0 10164.0 10340.6 10361.9 6495.9 6815.3 7343.4 7579.2 7585.8 8249.5 8253.0 8259.7 8337.5 8356.3 7320.5 7407.5 7413.9 7484.6 7493.4 7132.8 7203.9 7313.5 7332.9 7349.3 7040.5 7092.0 7141.2 7141.9 7158.6 4835.2 4927.2 4943.1 5051.0 5064.8 4882.2 4937.2 4945.9 4993.7 5005.6 02:38:07 INFO - PROCESS | 1669 | 02:38:07 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4656.03 Median:5211.64 stddev:1453.90 (27.9%) stddev-sans-first:1420.98 02:38:07 INFO - PROCESS | 1669 | Values: 313.6 316.7 322.7 325.7 327.6 4858.9 5245.7 5281.8 5294.8 5309.5 4331.5 4391.7 5141.6 5190.9 5202.8 3635.6 3861.5 3951.3 3978.0 4038.7 3975.5 4026.5 4032.6 4035.1 4075.3 3794.9 3971.7 3978.9 3984.1 3989.9 3835.5 3857.0 4019.7 4034.5 4047.0 3385.4 3542.7 3544.9 3557.3 3564.5 4608.9 5006.8 5042.9 5055.3 5066.3 3923.6 3954.6 4419.6 4603.5 4660.5 5468.6 5495.4 5501.4 5513.3 5521.5 3600.2 3702.9 3915.6 3925.4 3961.0 5469.8 5765.8 5812.1 5812.7 5835.2 3213.7 3818.4 3960.1 3960.4 3982.2 3638.3 4034.6 4045.9 4050.3 4063.7 4264.0 4376.0 4601.3 4601.7 4636.2 4396.5 5225.9 5539.2 5546.7 5558.5 1261.6 1319.0 1433.6 1453.3 1486.9 921.2 929.2 957.9 982.8 1001.7 4466.8 5021.3 5537.6 5569.8 5592.6 6123.4 6128.2 6136.6 6154.3 6158.1 5214.6 5490.6 5500.5 5514.1 5518.4 2830.8 3208.5 3230.7 3234.6 3266.6 5551.8 6146.2 6367.5 6382.6 6396.8 6213.3 6236.8 6258.0 6298.4 6316.6 5645.5 5728.1 6399.2 6400.2 6424.8 5391.5 5829.4 5961.8 6140.5 6221.0 5284.0 5833.9 6045.5 6080.0 6083.6 5677.4 6110.8 6386.7 6400.8 6442.8 4211.7 4217.9 4232.9 4237.0 4277.9 5897.9 6126.5 6126.8 6127.4 6149.4 5208.7 5567.2 5568.9 5576.2 5979.5 4863.5 5406.8 5521.6 5543.2 5548.9 4867.3 5119.1 5318.0 5408.5 5425.9 4782.8 5484.2 5558.6 5561.0 5595.8 4906.7 5259.3 5454.8 5455.5 5467.1 02:38:07 INFO - PROCESS | 1669 | 02:38:07 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:17139.31 Median:60286.35 stddev:23699.55 (39.3%) stddev-sans-first:23733.59 02:38:07 INFO - PROCESS | 1669 | Values: 623.0 652.2 732.5 744.1 750.7 11641.3 14106.0 14552.3 14634.9 14792.0 13560.5 13676.9 13764.7 13772.4 13780.7 729.9 753.0 866.1 888.2 923.1 910.9 914.0 915.8 917.3 917.4 973.4 973.9 976.2 977.2 977.2 750.8 930.3 964.8 967.3 967.5 712.9 713.2 713.6 714.3 716.8 14605.6 14644.0 14676.6 14677.3 14680.9 12099.0 12262.8 12384.5 12480.9 12854.4 9887.9 9973.6 10179.5 10369.7 10490.6 3417.8 3464.0 3516.1 3532.0 3729.9 71640.0 73634.6 73670.0 73717.0 73765.0 9311.6 9322.5 9346.4 9425.6 9623.9 20973.9 20976.6 20986.0 21022.8 21045.2 7045.8 7217.5 7296.8 7304.8 7485.7 3972.8 3974.3 4053.1 4117.6 4126.6 3100.9 3487.0 3519.0 3546.1 3565.8 620.3 647.5 648.8 650.5 668.3 388.4 507.8 525.6 536.6 552.2 6714.8 7211.4 7647.9 8072.5 8076.6 532.6 571.0 580.9 602.1 613.1 3598.5 3668.5 3779.2 3909.9 3921.1 68888.7 83823.3 88519.0 91135.0 91956.5 60322.0 77187.6 77248.6 77872.4 78068.8 68784.3 69413.7 69752.3 70376.6 70799.6 6703.9 7469.4 7544.7 7706.0 7739.2 34719.5 40932.4 42487.8 43179.0 43797.0 58222.0 58617.0 58704.0 59470.7 60250.7 10756.4 10949.9 10998.3 11095.4 11722.6 10496.9 11071.4 11604.8 11855.5 12128.6 10374.6 11267.1 11419.7 11638.6 11645.9 9760.8 11012.0 11520.7 11839.5 12035.4 10992.2 11156.5 11284.7 11437.7 11965.9 9439.3 9457.7 9493.2 9726.6 9729.0 9493.4 9600.1 9762.4 9820.3 9833.2 02:38:07 INFO - PROCESS | 1669 | -------- Summary: end -------- 02:38:07 INFO - PROCESS | 1669 | 02:38:08 INFO - TEST-INFO | 1669: exit 0 02:38:08 INFO - TEST-OK | dromaeo_css | took 1115443ms 02:38:08 INFO - TEST-START | kraken 02:38:08 INFO - operating with platform_type : mac_ 02:38:08 INFO - Initialising browser for kraken test... 02:38:08 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpzWY35c/profile http://localhost:49273/getInfo.html) 02:38:10 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 02:38:10 INFO - PROCESS | 1677 | colorDepth:24 02:38:10 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 02:38:10 INFO - PROCESS | 1677 | __metrics 02:38:10 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" 02:38:10 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:38:11 INFO - TEST-INFO | 1677: exit 0 02:38:11 INFO - Browser initialized. 02:38:11 INFO - Running cycle 1/1 for kraken test... 02:38:11 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpzWY35c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:38:23 INFO - PROCESS | 1680 | RSS: Main: 211345408 02:38:23 INFO - PROCESS | 1680 | 02:38:26 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 02:38:27 INFO - PROCESS | 1680 | RSS: Main: 269340672 02:38:27 INFO - PROCESS | 1680 | 02:38:29 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 02:38:30 INFO - PROCESS | 1680 | RSS: Main: 283889664 02:38:30 INFO - PROCESS | 1680 | 02:38:33 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 02:38:34 INFO - PROCESS | 1680 | RSS: Main: 371666944 02:38:34 INFO - PROCESS | 1680 | 02:38:36 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 02:38:36 INFO - PROCESS | 1680 | RSS: Main: 278065152 02:38:36 INFO - PROCESS | 1680 | 02:38:38 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:38:39 INFO - PROCESS | 1680 | RSS: Main: 277999616 02:38:39 INFO - PROCESS | 1680 | 02:38:42 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 02:38:42 INFO - PROCESS | 1680 | RSS: Main: 316669952 02:38:42 INFO - PROCESS | 1680 | 02:38:45 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 02:38:46 INFO - PROCESS | 1680 | RSS: Main: 318189568 02:38:46 INFO - PROCESS | 1680 | 02:38:49 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 02:38:50 INFO - PROCESS | 1680 | RSS: Main: 316411904 02:38:50 INFO - PROCESS | 1680 | 02:38:51 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:38:52 INFO - PROCESS | 1680 | RSS: Main: 307118080 02:38:52 INFO - PROCESS | 1680 | 02:38:54 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:38:54 INFO - PROCESS | 1680 | RSS: Main: 314490880 02:38:54 INFO - PROCESS | 1680 | 02:38:57 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:38:57 INFO - PROCESS | 1680 | RSS: Main: 316448768 02:38:57 INFO - PROCESS | 1680 | 02:39:00 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:39:01 INFO - PROCESS | 1680 | RSS: Main: 321982464 02:39:01 INFO - PROCESS | 1680 | 02:39:04 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:39:04 INFO - PROCESS | 1680 | RSS: Main: 319782912 02:39:04 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 02:39:06 INFO - PROCESS | 1680 | RSS: Main: 384294912 02:39:06 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | __start_tp_report 02:39:06 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 02:39:06 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 02:39:06 INFO - PROCESS | 1680 | |i|pagename|runs| 02:39:06 INFO - PROCESS | 1680 | |0;ai-astar;115;115;105;113;113;117;114;113;103;115 02:39:06 INFO - PROCESS | 1680 | |1;audio-beat-detection;180;144;152;171;109;152;142;164;139;124 02:39:06 INFO - PROCESS | 1680 | |2;audio-dft;181;188;204;184;186;185;184;184;186;184 02:39:06 INFO - PROCESS | 1680 | |3;audio-fft;73;78;73;149;55;56;59;62;132;57 02:39:06 INFO - PROCESS | 1680 | |4;audio-oscillator;90;89;139;78;90;87;129;80;77;139 02:39:06 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;89;81;133;86;87;84;88;84;86;84 02:39:06 INFO - PROCESS | 1680 | |6;imaging-darkroom;93;99;132;88;145;148;93;94;92;93 02:39:06 INFO - PROCESS | 1680 | |7;imaging-desaturate;87;87;84;100;139;138;84;90;84;84 02:39:06 INFO - PROCESS | 1680 | |8;json-parse-financial;59;64;63;67;91;91;92;93;59;59 02:39:06 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;66;64;70;77;79;77;71;67;69;68 02:39:06 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;82;82;101;81;70;94;103;102;83;87 02:39:06 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;135;149;151;130;136;136;137;137;176;139 02:39:06 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;189;169;170;177;207;168;166;166;177;162 02:39:06 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;69;68;69;70;68;68;67;70;65;69 02:39:06 INFO - PROCESS | 1680 | __end_tp_report 02:39:06 INFO - PROCESS | 1680 | __start_cc_report 02:39:06 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,2037 02:39:06 INFO - PROCESS | 1680 | __end_cc_report 02:39:06 INFO - PROCESS | 1680 | __startTimestamp1461922746846__endTimestamp 02:39:06 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | ------- Summary: start ------- 02:39:06 INFO - PROCESS | 1680 | Number of tests: 14 02:39:06 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:112.30 Median:114.50 stddev:4.57 (4.0%) stddev-sans-first:4.74 02:39:06 INFO - PROCESS | 1680 | Values: 115.0 115.0 105.0 113.0 113.0 117.0 114.0 113.0 103.0 115.0 02:39:06 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:147.70 Median:152.00 stddev:21.27 (14.0%) stddev-sans-first:19.08 02:39:06 INFO - PROCESS | 1680 | Values: 180.0 144.0 152.0 171.0 109.0 152.0 142.0 164.0 139.0 124.0 02:39:06 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:186.60 Median:185.50 stddev:6.38 (3.4%) stddev-sans-first:6.44 02:39:06 INFO - PROCESS | 1680 | Values: 181.0 188.0 204.0 184.0 186.0 185.0 184.0 184.0 186.0 184.0 02:39:06 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:79.40 Median:60.50 stddev:33.43 (55.3%) stddev-sans-first:35.38 02:39:06 INFO - PROCESS | 1680 | Values: 73.0 78.0 73.0 149.0 55.0 56.0 59.0 62.0 132.0 57.0 02:39:06 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:99.80 Median:83.50 stddev:25.35 (30.4%) stddev-sans-first:26.64 02:39:06 INFO - PROCESS | 1680 | Values: 90.0 89.0 139.0 78.0 90.0 87.0 129.0 80.0 77.0 139.0 02:39:06 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:90.20 Median:86.00 stddev:15.22 (17.7%) stddev-sans-first:16.13 02:39:06 INFO - PROCESS | 1680 | Values: 89.0 81.0 133.0 86.0 87.0 84.0 88.0 84.0 86.0 84.0 02:39:06 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:107.70 Median:93.00 stddev:23.93 (25.7%) stddev-sans-first:24.78 02:39:06 INFO - PROCESS | 1680 | Values: 93.0 99.0 132.0 88.0 145.0 148.0 93.0 94.0 92.0 93.0 02:39:06 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:97.70 Median:84.00 stddev:22.05 (26.2%) stddev-sans-first:23.04 02:39:06 INFO - PROCESS | 1680 | Values: 87.0 87.0 84.0 100.0 139.0 138.0 84.0 90.0 84.0 84.0 02:39:06 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:73.80 Median:79.00 stddev:15.66 (19.8%) stddev-sans-first:15.67 02:39:06 INFO - PROCESS | 1680 | Values: 59.0 64.0 63.0 67.0 91.0 91.0 92.0 93.0 59.0 59.0 02:39:06 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:70.50 stddev:5.16 (7.3%) stddev-sans-first:5.17 02:39:06 INFO - PROCESS | 1680 | Values: 66.0 64.0 70.0 77.0 79.0 77.0 71.0 67.0 69.0 68.0 02:39:06 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:88.50 Median:82.00 stddev:11.03 (13.4%) stddev-sans-first:11.44 02:39:06 INFO - PROCESS | 1680 | Values: 82.0 82.0 101.0 81.0 70.0 94.0 103.0 102.0 83.0 87.0 02:39:06 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:142.60 Median:138.00 stddev:13.36 (9.7%) stddev-sans-first:13.88 02:39:06 INFO - PROCESS | 1680 | Values: 135.0 149.0 151.0 130.0 136.0 136.0 137.0 137.0 176.0 139.0 02:39:06 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.10 Median:173.50 stddev:13.62 (7.8%) stddev-sans-first:13.48 02:39:06 INFO - PROCESS | 1680 | Values: 189.0 169.0 170.0 177.0 207.0 168.0 166.0 166.0 177.0 162.0 02:39:06 INFO - PROCESS | 1680 | 02:39:06 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:1.49 (2.2%) stddev-sans-first:1.56 02:39:06 INFO - PROCESS | 1680 | Values: 69.0 68.0 69.0 70.0 68.0 68.0 67.0 70.0 65.0 69.0 02:39:06 INFO - PROCESS | 1680 | -------- Summary: end -------- 02:39:06 INFO - PROCESS | 1680 | 02:39:07 INFO - TEST-INFO | 1680: exit 0 02:39:07 INFO - TEST-OK | kraken | took 59125ms 02:39:07 INFO - SUITE-END | took 1174s 02:39:08 INFO - Completed test suite (00:19:34) 02:39:08 INFO - javascript benchmark 02:39:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3351.530785690003, 3442.019024577994, 3449.9655003449966, 3472.756907016507, 3510.842012109455, 5688.60179787415, 5698.002849501331, 5700.429957004303, 5710.685912274823, 5736.483716465517, 5670.716464176795, 5714.314282286121, 5723.399043100477, 5728.570357223204, 5762.250907382045, 5044.545990860822, 5102.030614183293, 5137.409197942228, 5159.097157997345, 5175.223716442526, 5183.118869792144, 5196.636235463519, 5203.245529398235, 5206.869828254286, 5207.4792520748, 5128.948710512885, 5169.198774190007, 5178.326817513718, 5186.273921650972, 5226.895462090756, 4524.914020635057, 5196.974015129938, 5246.606504512177, 5260.500252476008, 5291.1798671206025, 5039.546440820307, 5074.238864170404, 5093.108705976476, 5154.974225128888, 5177.404598471148, 5505.229267902497, 5548.91676624851, 5552.111662134039, 5577.358603760579, 5591.776328946838, 6171.074338849164, 6190.071489276599, 6193.0710393440895, 6201.472874805647, 6204.162438070893, 6058.727357268934, 6117.066276512079, 6156.87686246277, 6179.752809887599, 6191.907121393183, 5735.598508104393, 5765.106408506688, 5771.249737534177, 5789.392113828029, 5810.33181184165, 6261.843453913598, 6262.655553944577, 6268.404501572343, 6272.1219029335925, 6272.278687950853, 5904.852378690482, 6019.976603977334, 6032.276126864805, 6100.3899610039525, 6111.5110791137495, 6228.128062071313, 6240.906386404207, 6245.875082498324, 6252.3122456529745, 6271.059341098871, 6096.146539484475, 6151.292601350812, 6171.6297022178815, 6187.969060154671, 6192.504599632019, 6048.697565121726, 6108.827782940854, 6118.923762351576, 6168.599041062406, 6193.256809182927, 4834.419869615669, 4849.127157111683, 4859.854204373874, 4867.0, 4873.829415970424, 4351.673624478177, 4381.868543943687, 4401.361802538614, 4433.135538569948, 4457.509673935864, 5608.887822243532, 5636.689982051026, 5646.491815736604, 5652.576056795757, 5660.037793575102, 5218.843434696965, 6203.038529027917, 6253.74985000604, 6262.718177682015, 6267.0, 5471.72641367938, 5544.91682624761, 5575.414581468968, 5637.943620563824, 5653.886922261532, 5279.498447647467, 5295.099833028395, 5304.389995150607, 5309.548688361455, 5330.7068111253475, 6251.0623406488485, 6292.779752708633, 6294.464970477561, 6299.937000629935, 6301.991681330965, 6023.66869822164, 6124.564025909317, 6126.517673797367, 6133.334663918993, 6142.283918229586, 5156.5616922562, 6239.875202496016, 6279.026750853625, 6307.716152773044, 6307.936920630735, 6118.816435506942, 6125.724342404514, 6157.630542167484, 6160.7535698571555, 6167.167432396748, 6181.474574661204, 6196.225471816023, 6227.408396202353, 6270.6551139685935, 6290.370962903673, 5900.380460051632, 6016.939830601638, 6037.698115094316, 6134.04922237054, 6137.539684523589, 6057.394260573908, 6069.968105422001, 6072.392760724069, 6078.54410919174, 6094.2077529920825, 5812.505936995402, 5883.970580147072, 5895.20414744004, 5910.527157827278, 5911.586188966731, 5869.06095024794, 5954.4343287389365, 5956.434138756811, 6084.665343405978, 6094.360092190256, 5879.147523609138, 5900.232969713909, 5913.526917846648, 5924.6149000314845, 5926.022206336079], "value": 5647.374090582027, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.31108004194334, 512.1344927073249, 581.828360633613, 590.9497692696121, 626.8432891777056, 29191.416171676552, 30705.23236919078, 38121.23757524851, 39416.80291598545, 39444.80277598608, 40352.789416317486, 41543.0, 43746.56253437464, 44405.77797111018, 44526.55473445264, 9522.000189980055, 9730.367526110815, 9731.708048758548, 9743.95128024359, 9745.415275083518, 8303.46027508213, 8546.914530854705, 11123.1657625678, 11409.885901141008, 11412.828807567894, 11409.714757131096, 11556.364399957998, 11582.6525204244, 11599.884001160008, 11600.303981761079, 8325.333973282122, 11150.0, 11457.427128643576, 11642.941785291105, 11645.18483706141, 11272.774544509146, 11426.485808138654, 11426.71433214168, 11437.542498300058, 11465.254758440675, 37715.0, 38116.809415952746, 38822.80588597039, 38829.611703883165, 38836.41745373821, 3225.4194245035883, 3389.932201355959, 3646.47126166707, 3787.6401741834484, 3828.0, 28103.297417564725, 28682.569761453597, 28829.135125946254, 29044.12867613975, 29112.981045663513, 2708.776615623804, 2734.1387462949306, 2753.9449211015667, 2764.9861750691116, 2768.5847122931723, 484491.0, 485007.0, 485248.0, 486094.0, 486589.0, 61904.69047654733, 62255.0, 62328.688356558836, 62339.6883015582, 62575.06137407943, 63323.36676633267, 64728.67635661886, 64755.676221619535, 64780.35219647837, 64811.0, 14633.560993170222, 17134.0, 17228.483145505656, 17340.566485837953, 17372.739408908878, 11886.049116070693, 12161.756764864653, 12174.878251217551, 13202.73594528104, 13305.334733263298, 17268.65462690739, 17523.73714394285, 18787.34244301443, 19234.326798561982, 19247.51881202981, 7245.492815502969, 7884.921150788534, 7959.84080318402, 8225.259726624632, 8297.087320394752, 815.604431850552, 985.7732721473958, 1091.9563217471211, 1100.3068067117665, 1117.3296022386503, 25552.0, 29843.0, 29894.85052574723, 30394.696053039628, 30561.541576876363, 768.3353898877542, 1113.4822307626991, 1121.8149005414016, 1125.6791814332876, 1142.0749193153572, 14633.707325853424, 15003.0, 15169.848301517064, 15177.317020734094, 15521.844781552265, 665004.0, 676375.0, 676820.0, 676915.0, 677942.0, 59544.40455595475, 61297.38702613006, 61336.386636133066, 61433.0, 61525.384746152864, 1332.4936524120965, 1358.8369395672385, 1360.149906308557, 1417.1567917089315, 1520.1107352199087, 23229.3031209064, 30008.69991300059, 30165.396692066475, 30552.388952221274, 30592.84703576468, 54100.729496352265, 54441.0, 54657.453425466825, 54701.0, 54701.0, 481176.0, 495828.0, 497016.0, 497079.0, 498022.0, 22225.110995560644, 24616.6307505384, 25315.87342063278, 26762.063327783435, 27177.72822271752, 41997.0, 42449.57550424456, 43879.78060109679, 44045.0, 44064.67805965826, 35206.59173633026, 35678.643213567535, 35870.0, 35988.83958652548, 36706.8164659175, 34219.0, 36637.63362366342, 36872.0, 37477.063073423386, 37481.437778433894, 36379.636203638685, 36950.07624809401, 37010.0, 37232.06919827026, 37555.62444375631, 31482.685173147976, 32656.51015234726, 32656.83671581627, 32673.18317042093, 33716.49425258672, 24297.0, 24561.0, 24614.630780537947, 24992.50014999726, 25083.372915677253], "value": 20022.84191611664, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.341153567774, 396.87696813987645, 398.73085667174666, 398.8344836892689, 408.8099033949213, 13942.442302307922, 19789.50526236845, 20726.585468290625, 20732.89633551832, 20830.687539686878, 16180.029198248083, 17616.03111828849, 19822.80177198231, 20453.0, 20691.689624655606, 5734.197212390269, 5910.674912879811, 5957.582969192136, 5964.552658550604, 5967.850803729898, 5862.794802181925, 5886.0, 5898.7640494380175, 5899.32157801854, 5920.111983202511, 5615.241942337793, 5798.681072541009, 5829.067349224125, 5834.445727655866, 5835.649861008353, 5808.709564521778, 5876.9118463223085, 5879.559033072515, 5890.322612899528, 5894.263217097863, 5002.449730529639, 5163.0, 5179.456157103526, 5183.222516622499, 5188.7146206958605, 24114.0, 24819.62770558443, 25487.617685734727, 25521.0, 26127.73872261272, 2177.1182671018264, 2208.2823082498094, 2242.2264318809985, 2417.8065754739578, 2874.1090262018633, 13333.866661333457, 14281.21453320067, 14375.353109110116, 14601.488947886774, 15092.924535377362, 1593.6015996001, 1670.156570931684, 1703.3442124781925, 1772.929082836685, 1805.1876655505075, 107852.0, 107976.0, 110561.44719276433, 111165.99999999919, 114014.0, 10397.84403233952, 10684.732881677864, 10964.807038592313, 10965.61620343284, 11045.889541104487, 13738.381772820247, 13768.518101866586, 13800.0, 13804.309784510735, 13821.723565528835, 7466.477346585687, 7624.123225829101, 8596.613152408157, 8696.434731742416, 8702.47785132894, 8661.740147795575, 8667.306615470747, 8682.34882383811, 8795.208431241219, 8797.51613661242, 6220.129181914535, 6355.459785918156, 6403.903941440882, 6419.614823110628, 6446.484281257488, 1819.699749541311, 1846.3537761783268, 1847.7136043913215, 1850.2136591948367, 1888.6222755448691, 962.2302158273353, 970.7281961050917, 1053.7629033467408, 1064.2762921213493, 1093.9671809845718, 19747.703784443245, 20311.390658280274, 20727.170913163303, 20747.377578672666, 20769.896150519453, 998.0119681515248, 1058.216919479595, 1066.2163309967168, 1071.6604244694133, 1117.608836907076, 9437.622495100119, 9895.554700038516, 10823.567057317776, 11090.0, 11111.111111111091, 627528.0, 633021.0, 635033.0, 648368.0, 649000.0, 108975.45512272547, 110836.0, 112282.43858780654, 119186.40406798084, 121948.39025804991, 1024.3085917005992, 1041.6979076067855, 1058.7882423515327, 1060.8408738689259, 1072.3780207479642, 17780.199891005195, 17825.197866095798, 17948.0, 18363.908180459013, 18686.43940681782, 37700.62299376971, 48556.54330370094, 49147.26279105888, 49547.50452495429, 49739.75650608764, 67297.0, 68054.65972670105, 68682.0, 69106.65446672734, 70641.64679176571, 7676.462647614837, 7826.2173782621285, 7887.684492620231, 7920.881186782318, 8235.094139644514, 12131.393430328624, 12295.200811947196, 12439.191452555555, 12717.10980231375, 12728.554500592434, 10787.676369708608, 10917.017468427879, 10965.561377545127, 11067.72330691739, 11109.388983605979, 10454.738631534272, 10498.47507624631, 10754.462276886281, 10953.014228719454, 10989.725256868642, 8837.867431988398, 9591.712248632552, 11085.667429977113, 11116.22186446941, 11547.769044619228, 7017.4386049116965, 7565.243475652611, 7874.488158269694, 7907.327877130509, 7945.8808117879435, 10838.891611084104, 10870.293430926964, 11134.944325278322, 11210.55157793679, 11220.934011268917], "value": 9558.132378428038, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1406.2336026865357, 1591.4589039726495, 1600.6478574713562, 1821.808710085441, 1944.1057113727684, 7825.2174782521715, 8783.648654053839, 8982.730518084452, 8986.370954033204, 9003.549822508865, 8594.398392112538, 8777.0, 8777.297816174707, 8816.559172041389, 8820.20618144367, 2433.7201221859536, 2468.950620987579, 2470.654108424822, 2472.258322503251, 2472.6538284640164, 2366.372911178539, 2417.7340492545804, 2417.842840215389, 2426.4783071639595, 2429.2833614083797, 2421.673074134987, 2443.535728211643, 2449.742777008415, 2461.310832966772, 2463.9753602464016, 2393.796527295182, 2422.0432928993127, 2431.610942249232, 2434.294054724128, 2435.4520232947534, 2389.5459862626044, 2406.446517301013, 2416.7704068113503, 2418.1657328221913, 2421.6004359280696, 8665.09016553259, 8895.4662720237, 8945.373823832335, 8948.239399651038, 8962.14859588338, 5239.79040838366, 5454.209139674765, 5496.972515137399, 5512.531434828044, 5523.420040895729, 3458.8789392371145, 3498.5276987606603, 3572.696320812708, 3649.6897763690117, 3656.8171591420587, 1020.3163880200209, 1046.7173863057008, 1050.4852622215135, 1053.0995998421315, 1068.305601359115, 11715.7071073223, 11832.881671183264, 11870.990965768007, 11874.109441791814, 11877.762444751143, 4571.885702857388, 4588.472325682523, 4685.6017238535105, 4718.905621887612, 4725.8582242532775, 9208.171264586086, 9228.0, 9229.953850230706, 9233.584488698025, 9240.907590924138, 5232.73836308183, 5331.840044798662, 5341.439148889388, 5352.2506849041165, 5356.116240820299, 3762.9087564606507, 4482.058767658763, 4638.744869032168, 4981.626378021663, 5014.498550145029, 3797.1456422305314, 3862.4592557042038, 3907.9609203908167, 3925.0776067624083, 3952.8418863245147, 1200.6458094862037, 1202.6812894582943, 1203.5426537915537, 1203.9699007524707, 1208.5738828182828, 830.2776584371635, 951.7763325618474, 963.7590602349412, 970.7767213540925, 984.7341217871278, 5784.88430231393, 5994.640321580632, 6024.728887200086, 6044.425779550936, 6219.782307619212, 466.2656316301838, 471.5166953872308, 481.57380718064064, 484.1648156929339, 484.4211167654619, 3800.619938006177, 4027.637512623878, 4028.7784171870244, 4064.9187016260103, 4104.958950410458, 10300.896991030144, 10647.0, 10929.961653642891, 10930.180236482296, 11011.174161937726, 10349.534270957825, 10680.198985076151, 10691.893081069089, 10841.891581084088, 10972.890271097347, 6512.934870651327, 8006.039275287003, 8237.670493180205, 8245.83508329842, 8423.862778524943, 5802.970985145047, 6100.206973093469, 6175.104609831459, 6176.042713379434, 6224.782132625336, 9478.289128315266, 10138.290319677846, 10143.7464063399, 10199.439030853078, 10299.3820370778, 9899.50502474859, 10131.0, 10164.0, 10340.638077667245, 10361.896381036395, 6495.894713822854, 6815.284395138439, 7343.4492413068165, 7579.242075792376, 7585.848283034419, 8249.463784853966, 8253.0, 8259.710910118118, 8337.499750015018, 8356.331493480386, 7320.524165929198, 7407.51849630087, 7413.9258607415395, 7484.625768711651, 7493.4379921505015, 7132.786016419515, 7203.927960720535, 7313.451491138292, 7332.890006650011, 7349.30181632744, 7040.471964602778, 7092.0, 7141.214466408795, 7141.857162856818, 7158.570485770871, 4835.20219163834, 4927.18701414277, 4943.110240156662, 5051.0, 5064.797408103782, 4882.218844984927, 4937.185364414975, 4945.851624451272, 4993.700377977333, 5005.649604527648], "value": 4522.575582032519, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.5657114895869, 316.67382595926193, 322.7289077175171, 325.6710722170607, 327.6166884744848, 4858.927116093257, 5245.685258884465, 5281.837995640963, 5294.841154765353, 5309.50453286955, 4331.45856767904, 4391.6706247031525, 5141.562967147787, 5190.922136167951, 5202.8179013734525, 3635.636436356363, 3861.4980052593264, 3951.308521008812, 3977.98011009946, 4038.6567141792984, 3975.4633124528254, 4026.4765580474495, 4032.5967403259733, 4035.132446523997, 4075.332201019077, 3794.924101517968, 3971.7418367806, 3978.860739874105, 3984.1434091670285, 3989.880303590897, 3835.5397352317623, 3857.0164608024916, 4019.733783858075, 4034.4755181826467, 4046.979765101156, 3385.356782211372, 3542.6988705960025, 3544.8936531904083, 3557.314227758052, 3564.518789963348, 4608.861734147981, 5006.774695138691, 5042.890564075898, 5055.266986286968, 5066.265391518246, 3923.5684074751753, 3954.5649978502342, 4419.558044195587, 4603.49361570227, 4660.533946605312, 5468.6171967962255, 5495.395506494282, 5501.4223506532035, 5513.255710479074, 5521.475459831309, 3600.1539638185004, 3702.9259414811822, 3915.57081665193, 3925.410208865414, 3960.960390396117, 5469.808556700536, 5765.827025189251, 5812.111063904248, 5812.733034116698, 5835.183074369592, 3213.6984521268923, 3818.446325282874, 3960.108975480494, 3960.405939109157, 3982.2234664240195, 3638.308721342936, 4034.5561988181275, 4045.8583949561626, 4050.3316952702476, 4063.715539912237, 4263.978680106579, 4376.0, 4601.28680054592, 4601.74690392025, 4636.188666983265, 4396.493998420933, 5225.921611175835, 5539.224508568804, 5546.69493177871, 5558.527525160407, 1261.564760157744, 1319.0041518653354, 1433.5770947570493, 1453.3096779030088, 1486.9033512821632, 921.1709461484718, 929.2380248196414, 957.9377340472847, 982.8181786369402, 1001.737810358953, 4466.754328511963, 5021.271915572294, 5537.612367134262, 5569.805056823073, 5592.636478628876, 6123.357047510036, 6128.161551534522, 6136.6318020918, 6154.292256390483, 6158.107074474176, 5214.60890433219, 5490.615656904059, 5500.477454641803, 5514.077538904387, 5518.392976772552, 2830.7593854522183, 3208.4866421372467, 3230.7415406767404, 3234.5956755405573, 3266.608007039123, 5551.834114835849, 6146.200993870768, 6367.522435817332, 6382.5532212744665, 6396.84007899797, 6213.285472170668, 6236.750529978841, 6258.026153392629, 6298.401651843067, 6316.62100273985, 5645.548356131581, 5728.112142617901, 6399.232092148972, 6400.167978162809, 6424.807255782334, 5391.4608539145775, 5829.358770535323, 5961.837441698827, 6140.539459540463, 6221.0, 5283.996040752245, 5833.854153646193, 6045.455908968214, 6079.969600151971, 6083.6349819011, 5677.375488696158, 6110.791834287035, 6386.680665966776, 6400.807975760735, 6442.774502892376, 4211.684123690797, 4217.894552636086, 4232.851850185229, 4236.95763042378, 4277.893052673708, 5897.911531326948, 6126.468382904274, 6126.816195514141, 6127.387261273837, 6149.446549810538, 5208.661437006583, 5567.220589117608, 5568.860778480408, 5576.219329293821, 5979.4618484336615, 4863.489333619919, 5406.783473718382, 5521.570508976038, 5543.223948647112, 5548.944510554843, 4867.269909513601, 5119.13328536021, 5318.048483091921, 5408.512659018801, 5425.914817036687, 4782.808687652455, 5484.2047903053035, 5558.555315574825, 5560.972195138998, 5595.804146854841, 4906.748779061317, 5259.289995850664, 5454.754536045808, 5455.509004189642, 5467.1411720015385], "value": 4201.864905038827, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.9875402491949, 652.2075678051173, 732.5283100937658, 744.1145037405487, 750.6584504050658, 11641.301521908681, 14106.012579119466, 14552.272386380691, 14634.926825365857, 14792.0, 13560.45758169672, 13676.93161534191, 13764.724705505909, 13772.449102035907, 13780.655483612889, 729.8941653460245, 752.9849403011937, 866.0906048648925, 888.2316795971502, 923.0907556057278, 910.9180173784358, 913.954302284883, 915.8214148241095, 917.2661870503604, 917.4220241248005, 973.3721749471587, 973.9366941148838, 976.1897624971257, 977.1694060048937, 977.1791694976218, 750.7897788619196, 930.3301622831584, 964.7781010367585, 967.2842096848352, 967.4920666649995, 712.9251428599974, 713.1905287498727, 713.5575942915426, 714.2928500784238, 716.8172116110411, 14605.6348591285, 14644.0, 14676.559703208814, 14677.26613669323, 14680.926595367062, 12099.0, 12262.816057759052, 12384.504619815287, 12480.875191248062, 12854.357282135948, 9887.851682224773, 9973.601055957753, 10179.491025448771, 10369.688909332732, 10490.58037678492, 3417.8461969211444, 3464.0, 3516.1385460562324, 3531.969385627913, 3729.8881033569037, 71640.0, 73634.63182684052, 73670.0, 73717.0, 73765.0, 9311.627534898664, 9322.487263200454, 9346.392484488486, 9425.622975081056, 9623.855642165374, 20973.895130524248, 20976.580468390548, 20985.950702464816, 21022.789772102387, 21045.158193672083, 7045.8238544035785, 7217.5308604941565, 7296.7810965671115, 7304.780856574312, 7485.737999170003, 3972.801359931992, 3974.28462876685, 4053.1285773558825, 4117.608827161415, 4126.649234815014, 3100.9224769380494, 3486.965130348664, 3518.997085830526, 3546.113471632092, 3565.8038807865296, 620.3269452643842, 647.4917190005823, 648.7794149989027, 650.4926157597082, 668.3316683316683, 388.4360987935735, 507.7841917185182, 525.5637820608886, 536.6136381805172, 552.2379116818845, 6714.83212919681, 7211.387032102225, 7647.923520764833, 8072.515649061075, 8076.555789431638, 532.5739408473206, 570.9978986878085, 580.851882769891, 602.0908428273339, 613.1048668943304, 3598.5321908151773, 3668.486411902336, 3779.244151169777, 3909.9022524436546, 3921.058945852975, 68888.65555672189, 83823.3235335302, 88519.0, 91135.0, 91956.54021729849, 60322.0, 77187.61406192933, 77248.61375693085, 77872.44255114866, 78068.75934230194, 68784.31215687779, 69413.65293173502, 69752.30247697458, 70376.64811676012, 70799.64600176967, 6703.865922681616, 7469.365103966224, 7544.66049027806, 7706.036745406825, 7739.18738532446, 34719.4792078114, 40932.38601421041, 42487.787561061996, 43179.0, 43797.0, 58222.0, 58617.0, 58704.0, 59470.70264648649, 60250.69874650774, 10756.354618722902, 10949.945250273699, 10998.34009959405, 11095.389753563632, 11722.58970936013, 10496.895031049591, 11071.446427678746, 11604.825927611262, 11855.466504007169, 12128.636140915787, 10374.599429076978, 11267.098632109248, 11419.657410277705, 11638.592649257236, 11645.88354116448, 9760.804783904425, 11011.953864382871, 11520.711982200512, 11839.526418943145, 12035.398230088636, 10992.230543861853, 11156.49795759209, 11284.717882053013, 11437.656870293904, 11965.939871002485, 9439.339246252697, 9457.668981585612, 9493.193078588398, 9726.610935562498, 9729.0, 9493.430394176372, 9600.135987761136, 9762.36544624597, 9820.312578119749, 9833.16418104469], "value": 6665.320454872944, "unit": "score"}], "name": "dromaeo_css", "value": 7179.15468829736, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 105.0, 113.0, 113.0, 117.0, 114.0, 113.0, 103.0, 115.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 144.0, 152.0, 171.0, 109.0, 152.0, 142.0, 164.0, 139.0, 124.0], "value": 147.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 188.0, 204.0, 184.0, 186.0, 185.0, 184.0, 184.0, 186.0, 184.0], "value": 186.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 78.0, 73.0, 149.0, 55.0, 56.0, 59.0, 62.0, 132.0, 57.0], "value": 79.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 139.0, 78.0, 90.0, 87.0, 129.0, 80.0, 77.0, 139.0], "value": 99.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 81.0, 133.0, 86.0, 87.0, 84.0, 88.0, 84.0, 86.0, 84.0], "value": 90.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 99.0, 132.0, 88.0, 145.0, 148.0, 93.0, 94.0, 92.0, 93.0], "value": 107.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 84.0, 100.0, 139.0, 138.0, 84.0, 90.0, 84.0, 84.0], "value": 97.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 63.0, 67.0, 91.0, 91.0, 92.0, 93.0, 59.0, 59.0], "value": 73.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 70.0, 77.0, 79.0, 77.0, 71.0, 67.0, 69.0, 68.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 101.0, 81.0, 70.0, 94.0, 103.0, 102.0, 83.0, 87.0], "value": 88.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 149.0, 151.0, 130.0, 136.0, 136.0, 137.0, 137.0, 176.0, 139.0], "value": 142.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 169.0, 170.0, 177.0, 207.0, 168.0, 166.0, 166.0, 177.0, 162.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 70.0, 68.0, 68.0, 67.0, 70.0, 65.0, 69.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1540.4999999999998, "alertThreshold": 2.0}]} 02:39:08 INFO - Return code: 0 02:39:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:39:08 ERROR - # TBPL SUCCESS # 02:39:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:39:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:39:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:39:08 INFO - 2016-04-29 02:39:08.189 screenresolution[1684:9713] starting screenresolution argv=screenresolution get 02:39:08 INFO - 2016-04-29 02:39:08.191 screenresolution[1684:9713] Display 0: 1600x1200x32@60 02:39:08 INFO - 2016-04-29 02:39:08.197 screenresolution[1685:9716] starting screenresolution argv=screenresolution list 02:39:08 INFO - Available Modes on Display 0 02:39:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:39:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:39:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:39:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:39:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:39:08 INFO - Intel Iris: 02:39:08 INFO - Chipset Model: Intel Iris 02:39:08 INFO - Type: GPU 02:39:08 INFO - Bus: Built-In 02:39:08 INFO - VRAM (Dynamic, Max): 1536 MB 02:39:08 INFO - Vendor: Intel (0x8086) 02:39:08 INFO - Device ID: 0x0a2e 02:39:08 INFO - Revision ID: 0x0009 02:39:08 INFO - Displays: 02:39:08 INFO - Display: 02:39:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:39:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:39:08 INFO - Display Serial Number: 1600x1200 60 02:39:08 INFO - Main Display: Yes 02:39:08 INFO - Mirror: Off 02:39:08 INFO - Online: Yes 02:39:08 INFO - Rotation: Supported 02:39:08 INFO - Return code: 0 02:39:08 INFO - Running post-action listener: _resource_record_post_action 02:39:08 INFO - Running post-run listener: _resource_record_post_run 02:39:09 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 37885440; Write bytes: 653113344; Read time: 394; Write time: 1961 02:39:09 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 356397568; Write bytes: 346920960; Read time: 27490; Write time: 739 02:39:09 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30670336; Write bytes: 295403520; Read time: 303; Write time: 1187 02:39:09 INFO - Running post-run listener: _upload_blobber_files 02:39:09 INFO - Blob upload gear active. 02:39:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:39:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:39:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:39:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:39:09 INFO - (blobuploader) - INFO - Open directory for files ... 02:39:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:39:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:39:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:39:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:39:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:39:11 INFO - (blobuploader) - INFO - Done attempting. 02:39:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:39:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:39:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:39:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:39:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:39:11 INFO - (blobuploader) - INFO - Done attempting. 02:39:11 INFO - (blobuploader) - INFO - Iteration through files over. 02:39:11 INFO - Return code: 0 02:39:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:39:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:39:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dee223659ddf89d2a28f1953dba59a1d98ae19f45fcea474aef1a4c22744cdd9ea67abd2dd54d070a27ee491ab657822da23e7083c95afa3818a48b1e4fdbcc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/766476f63eee880539681a6480209dbe630b6a6dc9f44f5b840583ef95654971f46c10a45fcef1bae7e04661717da9d6d0ecf5881749c0e4e748f3b657d2d641"} 02:39:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:39:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:39:11 INFO - Contents: 02:39:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dee223659ddf89d2a28f1953dba59a1d98ae19f45fcea474aef1a4c22744cdd9ea67abd2dd54d070a27ee491ab657822da23e7083c95afa3818a48b1e4fdbcc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/766476f63eee880539681a6480209dbe630b6a6dc9f44f5b840583ef95654971f46c10a45fcef1bae7e04661717da9d6d0ecf5881749c0e4e748f3b657d2d641"} 02:39:11 INFO - Running post-run listener: copy_logs_to_upload_dir 02:39:11 INFO - Copying logs to upload dir... 02:39:11 INFO - mkdir: /builds/slave/test/build/upload/logs 02:39:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1296.432466 ========= master_lag: 2.56 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 38 secs) (at 2016-04-29 02:39:14.565166) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 02:39:14.572208) ========= 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.MBTaEIFiae/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.bXM4vkQy4K/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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/dee223659ddf89d2a28f1953dba59a1d98ae19f45fcea474aef1a4c22744cdd9ea67abd2dd54d070a27ee491ab657822da23e7083c95afa3818a48b1e4fdbcc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/766476f63eee880539681a6480209dbe630b6a6dc9f44f5b840583ef95654971f46c10a45fcef1bae7e04661717da9d6d0ecf5881749c0e4e748f3b657d2d641"} build_url:https://queue.taskcluster.net/v1/task/OJrOJL_vRSC9f0xPSoQFjA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011049 build_url: 'https://queue.taskcluster.net/v1/task/OJrOJL_vRSC9f0xPSoQFjA/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/dee223659ddf89d2a28f1953dba59a1d98ae19f45fcea474aef1a4c22744cdd9ea67abd2dd54d070a27ee491ab657822da23e7083c95afa3818a48b1e4fdbcc7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/766476f63eee880539681a6480209dbe630b6a6dc9f44f5b840583ef95654971f46c10a45fcef1bae7e04661717da9d6d0ecf5881749c0e4e748f3b657d2d641"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 02:39:14.615016) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 02:39:14.615331) ========= 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.MBTaEIFiae/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.bXM4vkQy4K/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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.005161 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 02:39:14.667951) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-29 02:39:14.668658) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-29 02:39:14.669516) ========= ========= Total master_lag: 2.69 =========