builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0106 starttime: 1464430189.63 results: success (0) buildid: 20160525183450 builduid: 63f91898e679451db7d589c242ad058b revision: 33693d8bdec17f07fc460ca7cc908ade88f97b86 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:49.628792) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:49.629128) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:49.629390) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:49.654687) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:49.654947) ========= 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.XbVfTbkUvw/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.1jVBotWv6e/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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-05-28 03:09:49-- 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% 65.0M=0s 2016-05-28 03:09:50 (65.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.479363 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:50.167561) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:50.167867) ========= 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.XbVfTbkUvw/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.1jVBotWv6e/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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.029400 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:50.217042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-28 03:09:50.217348) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 33693d8bdec17f07fc460ca7cc908ade88f97b86 --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 33693d8bdec17f07fc460ca7cc908ade88f97b86 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XbVfTbkUvw/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.1jVBotWv6e/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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-05-28 03:09:50,293 truncating revision to first 12 chars 2016-05-28 03:09:50,293 Setting DEBUG logging. 2016-05-28 03:09:50,293 attempt 1/10 2016-05-28 03:09:50,293 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/33693d8bdec1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-28 03:09:51,932 unpacking tar archive at: mozilla-aurora-33693d8bdec1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.917324 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-28 03:09:52.155339) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:52.155669) ========= script_repo_revision: 33693d8bdec17f07fc460ca7cc908ade88f97b86 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:52.156061) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:52.156326) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:52.172100) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-05-28 03:09:52.172376) ========= /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.XbVfTbkUvw/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.1jVBotWv6e/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:09:52 INFO - MultiFileLogger online at 20160528 03:09:52 in /builds/slave/test 03:09:52 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 03:09:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:09:52 INFO - {'append_to_log': False, 03:09:52 INFO - 'base_work_dir': '/builds/slave/test', 03:09:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:09:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:09:52 INFO - 'branch': 'Mozilla-Aurora', 03:09:52 INFO - 'buildbot_json_path': 'buildprops.json', 03:09:52 INFO - 'config_files': ('talos/mac_config.py',), 03:09:52 INFO - 'default_actions': ('clobber', 03:09:52 INFO - 'read-buildbot-config', 03:09:52 INFO - 'download-and-extract', 03:09:52 INFO - 'populate-webroot', 03:09:52 INFO - 'create-virtualenv', 03:09:52 INFO - 'install', 03:09:52 INFO - 'run-tests'), 03:09:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:09:52 INFO - 'download_minidump_stackwalk': True, 03:09:52 INFO - 'download_symbols': 'ondemand', 03:09:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:09:52 INFO - 'tooltool.py': '/tools/tooltool.py', 03:09:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:09:52 INFO - '/tools/misc-python/virtualenv.py')}, 03:09:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:09:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:09:52 INFO - 'installer_path': 'installer.exe', 03:09:52 INFO - 'log_level': 'info', 03:09:52 INFO - 'log_name': 'talos', 03:09:52 INFO - 'log_to_console': True, 03:09:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:09:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:09:52 INFO - 'opt_config_files': (), 03:09:52 INFO - 'pip_index': False, 03:09:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:09:52 INFO - 'cmd': ('bash', 03:09:52 INFO - '-c', 03:09:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:09:52 INFO - 'enabled': True, 03:09:52 INFO - 'halt_on_failure': False, 03:09:52 INFO - 'name': 'check_screen_resolution'},), 03:09:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:09:52 INFO - 'cmd': ('bash', 03:09:52 INFO - '-c', 03:09:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:09:52 INFO - 'enabled': True, 03:09:52 INFO - 'halt_on_failure': False, 03:09:52 INFO - 'name': 'check_screen_resolution'},), 03:09:52 INFO - 'run_cmd_checks_enabled': True, 03:09:52 INFO - 'sps_profile': False, 03:09:52 INFO - 'sps_profile_interval': 0, 03:09:52 INFO - 'suite': 'dromaeojs', 03:09:52 INFO - 'system_bits': '32', 03:09:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:09:52 INFO - 'title': 't-yosemite-r7-0106', 03:09:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:09:52 INFO - 'use_talos_json': True, 03:09:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:09:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:09:52 INFO - 'work_dir': 'build'} 03:09:52 INFO - ##### 03:09:52 INFO - ##### Running clobber step. 03:09:52 INFO - ##### 03:09:52 INFO - Running pre-action listener: _resource_record_pre_action 03:09:52 INFO - Running main action method: clobber 03:09:52 INFO - rmtree: /builds/slave/test/build 03:09:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:09:54 INFO - Running post-action listener: _resource_record_post_action 03:09:54 INFO - ##### 03:09:54 INFO - ##### Running read-buildbot-config step. 03:09:54 INFO - ##### 03:09:54 INFO - Running pre-action listener: _resource_record_pre_action 03:09:54 INFO - Running main action method: read_buildbot_config 03:09:54 INFO - Using buildbot properties: 03:09:54 INFO - { 03:09:54 INFO - "project": "", 03:09:54 INFO - "product": "firefox", 03:09:54 INFO - "script_repo_revision": "production", 03:09:54 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:09:54 INFO - "repository": "", 03:09:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:09:54 INFO - "buildid": "20160525183450", 03:09:54 INFO - "pgo_build": "False", 03:09:54 INFO - "basedir": "/builds/slave/test", 03:09:54 INFO - "buildnumber": 18, 03:09:54 INFO - "slavename": "t-yosemite-r7-0106", 03:09:54 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:09:54 INFO - "platform": "yosemite_r7", 03:09:54 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:09:54 INFO - "branch": "mozilla-aurora", 03:09:54 INFO - "repo_path": "releases/mozilla-aurora", 03:09:54 INFO - "revision": "33693d8bdec17f07fc460ca7cc908ade88f97b86", 03:09:54 INFO - "stage_platform": "macosx64", 03:09:54 INFO - "builduid": "63f91898e679451db7d589c242ad058b", 03:09:54 INFO - "slavebuilddir": "test" 03:09:54 INFO - } 03:09:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:09:54 INFO - Running post-action listener: _resource_record_post_action 03:09:54 INFO - ##### 03:09:54 INFO - ##### Running download-and-extract step. 03:09:54 INFO - ##### 03:09:54 INFO - Running pre-action listener: _resource_record_pre_action 03:09:54 INFO - Running main action method: download_and_extract 03:09:54 INFO - mkdir: /builds/slave/test/build/tests 03:09: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')]} 03:09:54 INFO - https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:09:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:09:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:09:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/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 03:09:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/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 03:09:55 INFO - Downloaded 1199 bytes. 03:09:55 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:09:55 INFO - Using the following test package requirements: 03:09:55 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:09:55 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:09:55 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:09:55 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:09:55 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:09:55 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:09:55 INFO - u'jsshell-mac.zip'], 03:09:55 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:09:55 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:09:55 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:09:55 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:09:55 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:09:55 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:09:55 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:09:55 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:09:55 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:09:55 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:09:55 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:09:55 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:09:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:55 INFO - https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:09:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:09:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:09:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/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 03:09:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/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 03:09:57 INFO - Downloaded 18425354 bytes. 03:09:57 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'] 03:09:57 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 03:10:01 INFO - Return code: 0 03:10:01 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 03:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:01 INFO - https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:10:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:10:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:10:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/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 03:10:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/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 03:10:21 INFO - Downloaded 18425354 bytes. 03:10:21 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'] 03:10:21 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 03:10:24 INFO - Return code: 0 03:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:24 INFO - https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:10:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:10:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:10:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/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 03:10:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/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 03:10:26 INFO - Downloaded 10840145 bytes. 03:10:26 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'] 03:10:26 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 03:10:26 INFO - Return code: 0 03:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:26 INFO - https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:10:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:10:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:10:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 03:10:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/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 03:10:29 INFO - Downloaded 114134973 bytes. 03:10:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:10:29 INFO - mkdir: /builds/slave/test/properties 03:10:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:10:29 INFO - Writing to file /builds/slave/test/properties/build_url 03:10:29 INFO - Contents: 03:10:29 INFO - build_url:https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:10:32 INFO - Running post-action listener: _resource_record_post_action 03:10:32 INFO - Running post-action listener: set_extra_try_arguments 03:10:32 INFO - ##### 03:10:32 INFO - ##### Running populate-webroot step. 03:10:32 INFO - ##### 03:10:32 INFO - Running pre-action listener: _resource_record_pre_action 03:10:32 INFO - Running main action method: populate_webroot 03:10:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:10:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:10:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:10:32 INFO - '--tppagecycles', 03:10:32 INFO - '7'], 03:10:32 INFO - 'tests': ['tsvgm']}}, 03:10:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:10:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:10:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:10:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:10:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:10:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:10:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:32 INFO - 'talos_options': ['--disable-e10s'], 03:10:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:10:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:10:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:32 INFO - 'talos_options': ['--disable-e10s'], 03:10:32 INFO - 'tests': ['damp', 'tps']}, 03:10:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:32 INFO - 'tests': ['damp', 'tps']}, 03:10:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:10:32 INFO - 'tests': ['dromaeo_dom']}, 03:10:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:10:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:10:32 INFO - 'tests': ['a11yr', 03:10:32 INFO - 'ts_paint', 03:10:32 INFO - 'tpaint', 03:10:32 INFO - 'sessionrestore', 03:10:32 INFO - 'sessionrestore_no_auto_restore', 03:10:32 INFO - 'tabpaint']}, 03:10:32 INFO - 'other-e10s': {'tests': ['a11yr', 03:10:32 INFO - 'ts_paint', 03:10:32 INFO - 'tpaint', 03:10:32 INFO - 'sessionrestore', 03:10:32 INFO - 'sessionrestore_no_auto_restore', 03:10:32 INFO - 'tabpaint']}, 03:10:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:10:32 INFO - 'ts_paint', 03:10:32 INFO - 'tpaint', 03:10:32 INFO - 'sessionrestore', 03:10:32 INFO - 'sessionrestore_no_auto_restore', 03:10:32 INFO - 'tabpaint']}, 03:10:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:10:32 INFO - 'ts_paint', 03:10:32 INFO - 'tpaint', 03:10:32 INFO - 'sessionrestore', 03:10:32 INFO - 'sessionrestore_no_auto_restore', 03:10:32 INFO - 'tabpaint']}, 03:10:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:10:32 INFO - 'tests': ['a11yr', 03:10:32 INFO - 'ts_paint', 03:10:32 INFO - 'tpaint', 03:10:32 INFO - 'sessionrestore', 03:10:32 INFO - 'sessionrestore_no_auto_restore', 03:10:32 INFO - 'tabpaint']}, 03:10:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:10:32 INFO - 'tests': ['a11yr', 03:10:32 INFO - 'ts_paint', 03:10:32 INFO - 'tpaint', 03:10:32 INFO - 'sessionrestore', 03:10:32 INFO - 'sessionrestore_no_auto_restore', 03:10:32 INFO - 'tabpaint']}, 03:10:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:10:32 INFO - 'tests': ['tsvgx', 03:10:32 INFO - 'tsvgr_opacity', 03:10:32 INFO - 'tart', 03:10:32 INFO - 'tscrollx', 03:10:32 INFO - 'cart']}, 03:10:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:10:32 INFO - 'tsvgr_opacity', 03:10:32 INFO - 'tart', 03:10:32 INFO - 'tscrollx', 03:10:32 INFO - 'cart']}, 03:10:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:32 INFO - 'talos_options': ['--disable-e10s'], 03:10:32 INFO - 'tests': ['tp5o']}, 03:10:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:32 INFO - 'tests': ['tp5o']}, 03:10:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:32 INFO - 'talos_options': ['--disable-e10s', 03:10:32 INFO - '--xperf_path', 03:10:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:10:32 INFO - 'tests': ['tp5n']}, 03:10:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:32 INFO - 'talos_options': ['--xperf_path', 03:10:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:10:32 INFO - 'tests': ['tp5n']}}, 03:10:32 INFO - 'talos.zip': {'path': '', 03:10:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:10:32 INFO - Running post-action listener: _resource_record_post_action 03:10:32 INFO - ##### 03:10:32 INFO - ##### Running create-virtualenv step. 03:10:32 INFO - ##### 03:10:32 INFO - Running pre-action listener: _resource_record_pre_action 03:10:32 INFO - Running main action method: create_virtualenv 03:10:32 INFO - Creating virtualenv /builds/slave/test/build/venv 03:10:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:10:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:10:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:10:33 INFO - Using real prefix '/tools/python27' 03:10:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:10:33 INFO - Installing distribute.............................................................................................................................................................................................done. 03:10:36 INFO - Installing pip.................done. 03:10:36 INFO - Return code: 0 03:10:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:10: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')]} 03:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10: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')]} 03:10:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105100a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105110420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8218c91680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f9fe00>, '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 0x104e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f9ab20>, '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.XbVfTbkUvw/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.1jVBotWv6e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:10: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', 'pip>=1.5'] in /builds/slave/test/build 03:10: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 pip>=1.5 03:10:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XbVfTbkUvw/Render', 03:10:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:36 INFO - 'HOME': '/Users/cltbld', 03:10:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:36 INFO - 'LOGNAME': 'cltbld', 03:10:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:36 INFO - 'MOZ_NO_REMOTE': '1', 03:10:36 INFO - 'NO_EM_RESTART': '1', 03:10:36 INFO - 'PAGER': '/bin/cat', 03:10:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:36 INFO - 'PWD': '/builds/slave/test', 03:10:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:36 INFO - 'SHELL': '/bin/bash', 03:10:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1jVBotWv6e/Listeners', 03:10:36 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 03:10:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:36 INFO - 'USER': 'cltbld', 03:10:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:36 INFO - 'XPC_FLAGS': '0x0', 03:10:36 INFO - 'XPC_SERVICE_NAME': '0', 03:10:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:10:36 INFO - Cleaning up... 03:10:36 INFO - Return code: 0 03:10:36 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:10: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')]} 03:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10: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')]} 03:10:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10: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', '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 0x105100a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105110420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8218c91680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f9fe00>, '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 0x104e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f9ab20>, '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.XbVfTbkUvw/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.1jVBotWv6e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:10: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', 'psutil>=0.7.1'] in /builds/slave/test/build 03:10: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 psutil>=0.7.1 03:10:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XbVfTbkUvw/Render', 03:10:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:36 INFO - 'HOME': '/Users/cltbld', 03:10:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:36 INFO - 'LOGNAME': 'cltbld', 03:10:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:36 INFO - 'MOZ_NO_REMOTE': '1', 03:10:36 INFO - 'NO_EM_RESTART': '1', 03:10:36 INFO - 'PAGER': '/bin/cat', 03:10:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:36 INFO - 'PWD': '/builds/slave/test', 03:10:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:36 INFO - 'SHELL': '/bin/bash', 03:10:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1jVBotWv6e/Listeners', 03:10:36 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 03:10:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:36 INFO - 'USER': 'cltbld', 03:10:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:36 INFO - 'XPC_FLAGS': '0x0', 03:10:36 INFO - 'XPC_SERVICE_NAME': '0', 03:10:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:37 INFO - Downloading/unpacking psutil>=0.7.1 03:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:10:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:10:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:10:39 INFO - Installing collected packages: psutil 03:10:39 INFO - Running setup.py install for psutil 03:10:39 INFO - building 'psutil._psutil_osx' extension 03:10:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:10:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:10:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:10:40 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:10:40 INFO - building 'psutil._psutil_posix' extension 03:10:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:10:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:10:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:10:40 INFO - ^ 03:10:40 INFO - 1 warning generated. 03:10:40 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:10:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:10:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:10:40 INFO - Successfully installed psutil 03:10:40 INFO - Cleaning up... 03:10:41 INFO - Return code: 0 03:10:41 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:41 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 0x105100a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105110420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8218c91680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f9fe00>, '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 0x104e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f9ab20>, '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.XbVfTbkUvw/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.1jVBotWv6e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:10:41 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 03:10:41 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 03:10:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XbVfTbkUvw/Render', 03:10:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:41 INFO - 'HOME': '/Users/cltbld', 03:10:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:41 INFO - 'LOGNAME': 'cltbld', 03:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:41 INFO - 'MOZ_NO_REMOTE': '1', 03:10:41 INFO - 'NO_EM_RESTART': '1', 03:10:41 INFO - 'PAGER': '/bin/cat', 03:10:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:41 INFO - 'PWD': '/builds/slave/test', 03:10:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:41 INFO - 'SHELL': '/bin/bash', 03:10:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1jVBotWv6e/Listeners', 03:10:41 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 03:10:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:41 INFO - 'USER': 'cltbld', 03:10:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:41 INFO - 'XPC_FLAGS': '0x0', 03:10:41 INFO - 'XPC_SERVICE_NAME': '0', 03:10:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:41 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:43 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:10:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:10:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:10:43 INFO - Installing collected packages: mozsystemmonitor 03:10:43 INFO - Running setup.py install for mozsystemmonitor 03:10:43 INFO - Successfully installed mozsystemmonitor 03:10:43 INFO - Cleaning up... 03:10:43 INFO - Return code: 0 03:10:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:43 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 0x105100a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105110420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8218c91680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f9fe00>, '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 0x104e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f9ab20>, '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.XbVfTbkUvw/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.1jVBotWv6e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:10:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:10:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:10:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XbVfTbkUvw/Render', 03:10:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:43 INFO - 'HOME': '/Users/cltbld', 03:10:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:43 INFO - 'LOGNAME': 'cltbld', 03:10:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:43 INFO - 'MOZ_NO_REMOTE': '1', 03:10:43 INFO - 'NO_EM_RESTART': '1', 03:10:43 INFO - 'PAGER': '/bin/cat', 03:10:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:43 INFO - 'PWD': '/builds/slave/test', 03:10:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:43 INFO - 'SHELL': '/bin/bash', 03:10:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1jVBotWv6e/Listeners', 03:10:43 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 03:10:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:43 INFO - 'USER': 'cltbld', 03:10:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:43 INFO - 'XPC_FLAGS': '0x0', 03:10:43 INFO - 'XPC_SERVICE_NAME': '0', 03:10:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:44 INFO - Downloading/unpacking blobuploader==1.2.4 03:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:46 INFO - Downloading blobuploader-1.2.4.tar.gz 03:10:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:10:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:10:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:10: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 03:10: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 03:10: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 03:10: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 03:10: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 03:10: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 03:10:47 INFO - Downloading docopt-0.6.1.tar.gz 03:10:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:10:47 INFO - Installing collected packages: blobuploader, requests, docopt 03:10:47 INFO - Running setup.py install for blobuploader 03:10:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:10:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:10:48 INFO - Running setup.py install for requests 03:10:48 INFO - Running setup.py install for docopt 03:10:48 INFO - Successfully installed blobuploader requests docopt 03:10:48 INFO - Cleaning up... 03:10:48 INFO - Return code: 0 03:10:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:48 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 0x105100a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105110420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8218c91680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f9fe00>, '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 0x104e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f9ab20>, '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.XbVfTbkUvw/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.1jVBotWv6e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:10:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:10:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:10:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XbVfTbkUvw/Render', 03:10:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:48 INFO - 'HOME': '/Users/cltbld', 03:10:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:48 INFO - 'LOGNAME': 'cltbld', 03:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:48 INFO - 'MOZ_NO_REMOTE': '1', 03:10:48 INFO - 'NO_EM_RESTART': '1', 03:10:48 INFO - 'PAGER': '/bin/cat', 03:10:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:48 INFO - 'PWD': '/builds/slave/test', 03:10:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:48 INFO - 'SHELL': '/bin/bash', 03:10:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1jVBotWv6e/Listeners', 03:10:48 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 03:10:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:48 INFO - 'USER': 'cltbld', 03:10:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:48 INFO - 'XPC_FLAGS': '0x0', 03:10:48 INFO - 'XPC_SERVICE_NAME': '0', 03:10:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:10:49 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-kjpgv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:10:49 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-F02r5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:10:49 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-I0YvyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:10:49 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-oJJBaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:10:49 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-P79nC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:10:49 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-_hnBgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:10:49 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-5XC2o7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:10:49 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-n9n06v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:10:49 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-mXkuOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:10:50 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-Wqoa1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:10:50 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-9u2zlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:10:50 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-N7QLZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:10:50 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-KqRYHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:10:50 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-h2_IWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:10:50 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-4g4ffC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:10:50 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-Ine0Rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:10:50 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-EYnUWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:10:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:10:50 INFO - Running setup.py install for manifestparser 03:10:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:10:50 INFO - Running setup.py install for mozcrash 03:10:51 INFO - Running setup.py install for mozdebug 03:10:51 INFO - Running setup.py install for mozdevice 03:10:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:10:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:10:51 INFO - Running setup.py install for mozfile 03:10:51 INFO - Running setup.py install for mozhttpd 03:10:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:10:51 INFO - Running setup.py install for mozinfo 03:10:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:10:51 INFO - Running setup.py install for mozInstall 03:10:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:10:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:10:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:10:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:10:51 INFO - Running setup.py install for mozleak 03:10:52 INFO - Running setup.py install for mozlog 03:10:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:10:52 INFO - Running setup.py install for moznetwork 03:10:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:10:52 INFO - Running setup.py install for mozprocess 03:10:52 INFO - Running setup.py install for mozprofile 03:10:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:10:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:10:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:10:52 INFO - Running setup.py install for mozrunner 03:10:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:10:52 INFO - Running setup.py install for mozscreenshot 03:10:53 INFO - Running setup.py install for moztest 03:10:53 INFO - Running setup.py install for mozversion 03:10:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:10:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:10:53 INFO - Cleaning up... 03:10:53 INFO - Return code: 0 03:10:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:53 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 0x105100a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105110420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8218c91680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f9fe00>, '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 0x104e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f9ab20>, '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.XbVfTbkUvw/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.1jVBotWv6e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:10:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:10:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:10:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XbVfTbkUvw/Render', 03:10:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:53 INFO - 'HOME': '/Users/cltbld', 03:10:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:53 INFO - 'LOGNAME': 'cltbld', 03:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:53 INFO - 'MOZ_NO_REMOTE': '1', 03:10:53 INFO - 'NO_EM_RESTART': '1', 03:10:53 INFO - 'PAGER': '/bin/cat', 03:10:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:53 INFO - 'PWD': '/builds/slave/test', 03:10:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:53 INFO - 'SHELL': '/bin/bash', 03:10:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1jVBotWv6e/Listeners', 03:10:53 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 03:10:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:53 INFO - 'USER': 'cltbld', 03:10:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:53 INFO - 'XPC_FLAGS': '0x0', 03:10:53 INFO - 'XPC_SERVICE_NAME': '0', 03:10:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:10:53 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-LDm26y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:10:53 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-o_I5oZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:10:53 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-PT6pRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:10:53 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-fD49te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:10:54 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-vNJpxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:10:54 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-CYJq2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:10:54 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-nMPpZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:10:54 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-y8dGVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:10:54 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-7sdwWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:10:54 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-_hV5rt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:10:54 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)) 03:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:10:54 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-2ZXUK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:10:54 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-u28uvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:10:54 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)) 03:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:10:54 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-DLk1zM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:10:55 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-RCtCYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:10:55 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)) 03:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:10:55 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-_1NffV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:10:55 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-Xz_jQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:10:55 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-sUQQHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:10:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:58 INFO - Downloading blessings-1.6.tar.gz 03:10:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:10:58 INFO - Installing collected packages: blessings 03:10:58 INFO - Running setup.py install for blessings 03:10:58 INFO - Successfully installed blessings 03:10:58 INFO - Cleaning up... 03:10:58 INFO - Return code: 0 03:10:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:10:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:10:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:10:58 INFO - Reading from file tmpfile_stdout 03:10:58 INFO - Current package versions: 03:10:58 INFO - blessings == 1.6 03:10:58 INFO - blobuploader == 1.2.4 03:10:58 INFO - docopt == 0.6.1 03:10:58 INFO - manifestparser == 1.1 03:10:58 INFO - mozInstall == 1.12 03:10:58 INFO - mozcrash == 0.17 03:10:58 INFO - mozdebug == 0.1 03:10:58 INFO - mozdevice == 0.48 03:10:58 INFO - mozfile == 1.2 03:10:58 INFO - mozhttpd == 0.7 03:10:58 INFO - mozinfo == 0.9 03:10:58 INFO - mozleak == 0.1 03:10:58 INFO - mozlog == 3.1 03:10:58 INFO - moznetwork == 0.27 03:10:58 INFO - mozprocess == 0.22 03:10:58 INFO - mozprofile == 0.28 03:10:58 INFO - mozrunner == 6.11 03:10:58 INFO - mozscreenshot == 0.1 03:10:58 INFO - mozsystemmonitor == 0.0 03:10:58 INFO - moztest == 0.7 03:10:58 INFO - mozversion == 1.4 03:10:58 INFO - psutil == 3.1.1 03:10:58 INFO - requests == 1.2.3 03:10:58 INFO - wsgiref == 0.1.2 03:10:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:58 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 0x105100a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105110420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8218c91680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f9fe00>, '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 0x104e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f9ab20>, '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.XbVfTbkUvw/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.1jVBotWv6e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:10:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:10:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:10:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XbVfTbkUvw/Render', 03:10:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:58 INFO - 'HOME': '/Users/cltbld', 03:10:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:58 INFO - 'LOGNAME': 'cltbld', 03:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:58 INFO - 'MOZ_NO_REMOTE': '1', 03:10:58 INFO - 'NO_EM_RESTART': '1', 03:10:58 INFO - 'PAGER': '/bin/cat', 03:10:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:58 INFO - 'PWD': '/builds/slave/test', 03:10:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:58 INFO - 'SHELL': '/bin/bash', 03:10:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1jVBotWv6e/Listeners', 03:10:58 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 03:10:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:58 INFO - 'USER': 'cltbld', 03:10:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:58 INFO - 'XPC_FLAGS': '0x0', 03:10:58 INFO - 'XPC_SERVICE_NAME': '0', 03:10:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:10:59 INFO - Cleaning up... 03:10:59 INFO - Return code: 0 03:10:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105100a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105110420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8218c91680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f9fe00>, '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 0x104e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f9ab20>, '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.XbVfTbkUvw/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.1jVBotWv6e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:10:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:10:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:10:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XbVfTbkUvw/Render', 03:10:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:59 INFO - 'HOME': '/Users/cltbld', 03:10:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:59 INFO - 'LOGNAME': 'cltbld', 03:10:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:59 INFO - 'MOZ_NO_REMOTE': '1', 03:10:59 INFO - 'NO_EM_RESTART': '1', 03:10:59 INFO - 'PAGER': '/bin/cat', 03:10:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:59 INFO - 'PWD': '/builds/slave/test', 03:10:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:59 INFO - 'SHELL': '/bin/bash', 03:10:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1jVBotWv6e/Listeners', 03:10:59 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 03:10:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:59 INFO - 'USER': 'cltbld', 03:10:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:59 INFO - 'XPC_FLAGS': '0x0', 03:10:59 INFO - 'XPC_SERVICE_NAME': '0', 03:10:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:59 INFO - Downloading/unpacking jsonschema 03:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:11:01 INFO - Downloading/unpacking functools32 (from jsonschema) 03:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:02 INFO - Downloading functools32-3.2.3-2.tar.gz 03:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:11:02 INFO - warning: no files found matching '*.txt' 03:11:02 INFO - no previously-included directories found matching 'build' 03:11:02 INFO - no previously-included directories found matching 'dist' 03:11:02 INFO - no previously-included directories found matching '.git*' 03:11:02 INFO - Installing collected packages: jsonschema, functools32 03:11:02 INFO - Running setup.py install for functools32 03:11:02 INFO - warning: no files found matching '*.txt' 03:11:02 INFO - no previously-included directories found matching 'build' 03:11:02 INFO - no previously-included directories found matching 'dist' 03:11:02 INFO - no previously-included directories found matching '.git*' 03:11:02 INFO - Successfully installed jsonschema functools32 03:11:02 INFO - Cleaning up... 03:11:02 INFO - Return code: 0 03:11:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:02 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 0x105100a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105110420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8218c91680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f9fe00>, '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 0x104e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f9ab20>, '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.XbVfTbkUvw/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.1jVBotWv6e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:11:02 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 03:11:02 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 03:11:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XbVfTbkUvw/Render', 03:11:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:02 INFO - 'HOME': '/Users/cltbld', 03:11:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:02 INFO - 'LOGNAME': 'cltbld', 03:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:02 INFO - 'MOZ_NO_REMOTE': '1', 03:11:02 INFO - 'NO_EM_RESTART': '1', 03:11:02 INFO - 'PAGER': '/bin/cat', 03:11:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:02 INFO - 'PWD': '/builds/slave/test', 03:11:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:02 INFO - 'SHELL': '/bin/bash', 03:11:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1jVBotWv6e/Listeners', 03:11:02 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 03:11:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:02 INFO - 'USER': 'cltbld', 03:11:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:02 INFO - 'XPC_FLAGS': '0x0', 03:11:02 INFO - 'XPC_SERVICE_NAME': '0', 03:11:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:02 INFO - Downloading/unpacking flake8 03:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:11:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:11:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:05 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:11:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:11:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:11:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:11:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:11:06 INFO - no previously-included directories found matching 'docs/_build' 03:11:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:11:06 INFO - Running setup.py install for pep8 03:11:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:11:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:11:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:11:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:11:06 INFO - no previously-included directories found matching 'docs/_build' 03:11:06 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:11:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:11:06 INFO - Cleaning up... 03:11:06 INFO - Return code: 0 03:11:06 INFO - Running post-action listener: _resource_record_post_action 03:11:06 INFO - Running post-action listener: _start_resource_monitoring 03:11:06 INFO - Starting resource monitoring. 03:11:06 INFO - ##### 03:11:06 INFO - ##### Running install step. 03:11:06 INFO - ##### 03:11:06 INFO - Running pre-action listener: _resource_record_pre_action 03:11:06 INFO - Running main action method: install 03:11:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:11:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:11:07 INFO - Reading from file tmpfile_stdout 03:11:07 INFO - Detecting whether we're running mozinstall >=1.0... 03:11:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:11:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:11:07 INFO - Reading from file tmpfile_stdout 03:11:07 INFO - Output received: 03:11:07 INFO - Usage: mozinstall [options] installer 03:11:07 INFO - Options: 03:11:07 INFO - -h, --help show this help message and exit 03:11:07 INFO - -d DEST, --destination=DEST 03:11:07 INFO - Directory to install application into. [default: 03:11:07 INFO - "/builds/slave/test"] 03:11:07 INFO - --app=APP Application being installed. [default: firefox] 03:11:07 INFO - mkdir: /builds/slave/test/build/application 03:11:07 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'] 03:11:07 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 03:11:39 INFO - Reading from file tmpfile_stdout 03:11:39 INFO - Output received: 03:11:39 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:11:39 INFO - Running post-action listener: _resource_record_post_action 03:11:39 INFO - ##### 03:11:39 INFO - ##### Running run-tests step. 03:11:39 INFO - ##### 03:11:39 INFO - Running pre-action listener: _resource_record_pre_action 03:11:39 INFO - Running main action method: run_tests 03:11:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:11:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:11:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:11:39 INFO - 2016-05-28 03:11:39.202 screenresolution[1646:7891] starting screenresolution argv=screenresolution get 03:11:39 INFO - 2016-05-28 03:11:39.204 screenresolution[1646:7891] Display 0: 1600x1200x32@60 03:11:39 INFO - 2016-05-28 03:11:39.210 screenresolution[1647:7894] starting screenresolution argv=screenresolution list 03:11:39 INFO - Available Modes on Display 0 03:11:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:11:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:11:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:11:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:11:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:11:39 INFO - Intel Iris: 03:11:39 INFO - Chipset Model: Intel Iris 03:11:39 INFO - Type: GPU 03:11:39 INFO - Bus: Built-In 03:11:39 INFO - VRAM (Dynamic, Max): 1536 MB 03:11:39 INFO - Vendor: Intel (0x8086) 03:11:39 INFO - Device ID: 0x0a2e 03:11:39 INFO - Revision ID: 0x0009 03:11:39 INFO - Displays: 03:11:39 INFO - Display: 03:11:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:11:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:11:39 INFO - Display Serial Number: 1600x1200 60 03:11:39 INFO - Main Display: Yes 03:11:39 INFO - Mirror: Off 03:11:39 INFO - Online: Yes 03:11:39 INFO - Rotation: Supported 03:11:39 INFO - Return code: 0 03:11:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:11:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:11:39 INFO - Python 2.7.3 03:11:39 INFO - Return code: 0 03:11:39 INFO - grabbing minidump binary from tooltool 03:11:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:11:39 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 0x104f9fe00>, '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 0x104e08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f9ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:11:39 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:11:39 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:11:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:11:39 INFO - Return code: 0 03:11:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:11:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:11:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:11:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:11:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:11:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:11:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:11:40 INFO - Return code: 0 03:11:40 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/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0106', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:11:40 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/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0106 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:11:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XbVfTbkUvw/Render', 03:11:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:40 INFO - 'HOME': '/Users/cltbld', 03:11:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:40 INFO - 'LOGNAME': 'cltbld', 03:11:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:11:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:11:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:40 INFO - 'MOZ_NO_REMOTE': '1', 03:11:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:11:40 INFO - 'NO_EM_RESTART': '1', 03:11:40 INFO - 'PAGER': '/bin/cat', 03:11:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:40 INFO - 'PWD': '/builds/slave/test', 03:11:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:11:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:40 INFO - 'SHELL': '/bin/bash', 03:11:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1jVBotWv6e/Listeners', 03:11:40 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 03:11:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:40 INFO - 'USER': 'cltbld', 03:11:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:40 INFO - 'XPC_FLAGS': '0x0', 03:11:40 INFO - 'XPC_SERVICE_NAME': '0', 03:11:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:40 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/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0106', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:11:40 INFO - mozversion application_buildid: 20160525183450 03:11:40 INFO - mozversion application_changeset: 33693d8bdec17f07fc460ca7cc908ade88f97b86 03:11:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:11:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:11:40 INFO - mozversion application_name: Firefox 03:11:40 INFO - mozversion application_remotingname: firefox-dev 03:11:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:11:40 INFO - mozversion application_vendor: Mozilla 03:11:40 INFO - mozversion application_version: 48.0a2 03:11:40 INFO - mozversion platform_buildid: 20160525183450 03:11:40 INFO - mozversion platform_changeset: 33693d8bdec17f07fc460ca7cc908ade88f97b86 03:11:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:11:40 INFO - mozversion platform_version: 48.0a2 03:11:40 INFO - using testdate: 1464430300 03:11:40 INFO - actual date: 1464430300 03:11:40 INFO - starting webserver on 'localhost:49278' 03:11:40 INFO - SUITE-START | Running 2 tests 03:11:40 INFO - TEST-START | dromaeo_css 03:11:40 INFO - operating with platform_type : mac_ 03:11:40 INFO - Initialising browser for dromaeo_css test... 03:11:40 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/tmpWC1sBl/profile http://localhost:49278/getInfo.html) 03:11:43 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 03:11:43 INFO - PROCESS | 1665 | colorDepth:24 03:11:43 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 03:11:43 INFO - PROCESS | 1665 | __metrics 03:11:43 INFO - PROCESS | 1665 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:11:43 ERROR - PROCESS | 1665 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:11:44 INFO - TEST-INFO | 1665: exit 0 03:11:44 INFO - Browser initialized. 03:11:44 INFO - Running cycle 1/1 for dromaeo_css test... 03:11:44 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/tmpWC1sBl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:11:56 INFO - PROCESS | 1668 | RSS: Main: 211251200 03:11:56 INFO - PROCESS | 1668 | 03:12:47 INFO - PROCESS | 1668 | 1464430367402 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:14:57 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 03:14:59 INFO - PROCESS | 1668 | RSS: Main: 337027072 03:14:59 INFO - PROCESS | 1668 | 03:18:02 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 03:18:02 INFO - PROCESS | 1668 | RSS: Main: 357912576 03:18:02 INFO - PROCESS | 1668 | 03:21:04 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 03:21:05 INFO - PROCESS | 1668 | RSS: Main: 361381888 03:21:05 INFO - PROCESS | 1668 | 03:24:07 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 03:24:08 INFO - PROCESS | 1668 | RSS: Main: 344838144 03:24:08 INFO - PROCESS | 1668 | 03:27:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 03:27:12 INFO - PROCESS | 1668 | RSS: Main: 325091328 03:27:12 INFO - PROCESS | 1668 | 03:30:15 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 03:30:15 INFO - PROCESS | 1668 | RSS: Main: 364924928 03:30:15 INFO - PROCESS | 1668 | 03:30:15 INFO - PROCESS | 1668 | __start_tp_report 03:30:15 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 03:30:15 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 03:30:15 INFO - PROCESS | 1668 | |i|pagename|runs| 03:30:15 INFO - PROCESS | 1668 | |0;dojo.html;2885.9278518037045;3349.1124851914237;3425.1950791563995;3435.982820085899;3478.7564870459078;5585.692786896719;5591.776328946848;5595.720213989304;5595.804146854861;5617.494425501709;5565.053940830068;5624.690642014688;5624.943750562493;5647.068233741438;5697.743601537931;5104.464031276718;5107.4892510749;5135.922961155586;5200.375954885419;5260.184671375943;5201.86995325118;5226.294450249225;5236.73816309183;5240.397354304247;5254.106801843695;5162.561182299509;5187.273781670569;5208.0885844977165;5227.555657769075;5229.2156176573435;5214.296070030554;5235.188545775372;5236.81671141512;5252.317198764136;5279.894402111936;5069.188929771219;5091.567216786578;5138.100832354323;5169.793208271664;5190.792368305263;4559.293309537027;5432.6740395576;5506.504414602665;5514.420985796514;5523.613347065707;6126.264848218198;6175.01199808033;6176.289726681444;6182.567220294582;6185.103160041769;5273.91884663643;6069.6054756441135;6186.319504854462;6236.625802451822;6243.406876346739;5675.5459563234845;5713.771449142029;5720;5723.227364305849;5727.484526392603;6309.274433440213;6309.463695585835;6310.148130002391;6314.400131987454;6316.557840951089;5910.34986151523;6009.729562169713;6019.277686677539;6046.3349031606485;6059.818205453844;6146.907796383058;6155.230596175478;6155.876882462415;6160.014397696347;6165.907511387333;6040.788572399945;6094.240942309475;6094.781043791259;6102.176206212104;6140.600860944024;5991;6082.60463069899;6097.115918191837;6116.235470566179;6122;4832.565069143794;4850.5149485051215;4870.245112007645;4870.8782280442565;4872.293517439952;4335.566443355702;4357.128574285156;4407.471103467605;4422.137683151819;4435.512093669694;5625.915611265835;5632.155176723524;5643.012472817322;5643.463870932255;5646.632968857011;6042.758289668452;6245.875082498415;6256.968715156395;6258.593191442542;6270.749170033238;5588.832335029955;5612.270404847343;5647.040003199466;5667.829965101054;5695.145728140729;5344.171653393731;5344.331958505187;5345.144776835766;5354.063038968165;5362.383325917492;6251.281102673069;6303.432691057827;6374.107624932606;6375.171227740457;6412.775552855627;6131.284916201153;6238.968805155945;6242.126102345766;6250.937490625036;6258.155149055002;6359.141515895295;6372;6379.649119298298;6380.425761681286;6390.7763228286785;5714.200011998407;6071.453569178795;6120.571559990936;6147.907781383194;6160.229971253593;6131.254333328352;6167.1366008759705;6202.131701561875;6211.81364559064;6213;5816.680082595329;5904.734286957011;5957.136215248851;5959.135925290895;5965.343812180743;5777.3933736957015;5927.851803704942;5939.554533409925;5957.463828255478;6050.092486127116;5848.0350742127075;5851.502622277154;5858.8868115058;5865.442782935615;5868.2664666916635;5188.662736922088;5984.760609575568;6002.609830361012;6003.159557661844;6035.094735789491;5718.656880587178;5815.505682017076;5817.825465236049;5827.329857066492;5923.200367950271 03:30:15 INFO - PROCESS | 1668 | |1;ext.html;512.6821370750133;513.8818071843476;517.2783966366918;538.3620409814371;583.9692941957449;28599.857000714994;37871.24257514848;38790.224195516144;38806.41790373147;38873.02817429562;37240.81379593098;41475.58524414755;42164.73505794824;42200.36699449511;42346.36480452788;8375.41372103953;9371.25029997602;9601;9603.519824008807;9604.183644390236;8530.488170709747;11381.886181138207;11600.361980091091;11601.883981160165;11609.303441793478;11330.150238732087;11430.314181149157;11452.427378631035;11456.427178641075;11496.137789665767;8523;11293.548258069668;11374.31754094757;11409.429528523582;11612.419379031098;11285.943570282096;11394.772104557946;11410.144239182095;11416.429178541122;11420;38116;38594.03514912121;38607.80696096501;38620.61379386198;38653;3120.3135310231714;3163.572917656133;3203.727683146912;3371.8482668279985;3702.8518859245596;28086.719132808616;28467.857660711772;28906;29243.56134657982;29438.675259613156;2723.2647185260066;2752.4357506711076;2759.296379423234;2774.3064233941514;2847.330877243846;494611;499376.9999999964;499441;500342.4982875135;500937;59315;60389.69805150946;60436.697816511514;60579.394206058256;60601.39398605958;63074.68462657657;63149.36850631435;63152.05271920833;63357.683211583644;63386.366136338045;16745;17509.124543773014;17510.824891751174;17532.737008944878;17541.736873946902;12627.242365458102;12906.483740650456;12910.870891290966;12918.806217906738;13577.253251071088;13824.861751382357;15308.846911530742;15450.150241736812;15622.53132406007;15651.060936343856;5993.850153746192;6134.834381467507;6409.455196308273;7057.917914215319;7295.270472952769;955.7180631713522;958.5446912716543;1003.5484032185545;1009.9798004040025;1016.6035246253966;23712.881435592713;26616.733832661426;27126.05058822932;27365.86317068402;27519.58720619192;976.511744127936;1052.400131924811;1090.345792524479;1131.61942430235;1158.7972104881612;13925.658743641865;14121.717565648629;14176.191803735705;14198.929005354907;14301.070430421989;498072;504236;504711;506557;509492;58616;58648.120278195864;58686;58741.82516349734;58837;1349.709812390321;1469.6840179361488;1512.0625212368182;1513.4551561437654;1517.3020410611243;26782.732172678025;26937.73062269352;28780.434653406268;28845.27886802847;29337.266568335963;40017.1996560073;48150.518494814605;48404.273935891695;48449.51550484591;48646;413304;416839;416921;417179;611420;37698;38425;38880.2223955525;39462.80268598639;39490.802545988234;41954.580454196286;41990.58009419867;42113;43259.783701081295;43448.34827477654;35373.64626353807;35603.28793424065;36910.63089369072;37656.24687506289;37817;29723.554146687384;36067.27865442729;36401.635983639826;37108;37247.255054899295;36338;36702.082447937944;37387.6261237395;37983.430248545745;38765.41851872168;30917.536236956013;32251;32336.029919102464;32432;32445.51331729979;24293.51412971766;24458.633120503564;24714.752852471964;25052.248432547054;25158.24525264245 03:30:15 INFO - PROCESS | 1668 | |2;jquery.html;397.5944536572696;398.5735263268304;399.0741479766941;401.6585901983313;411.1078958659708;19329.90335048323;20022.89988550057;20595.897020514894;20607.896960515212;21401.46496337592;17272.827271727278;19979.00104994748;20497.282595109173;20791.960401979915;20818.375448736562;5720.1991721159175;5753.079507278836;5817.360090390053;5915.73379197935;5919.319278282988;5596.132599447093;5756.222909907151;5758;5805.680687562182;5805.883882322351;5637.3235211774645;5732.684702341369;5743.052396354597;5748;5768.019436695751;5598.300212473441;5707.058335374658;5737.913931291013;5742.6841523716175;5745.052066409058;4985.002999400098;5049.065922804256;5081.695098294114;5089.033083714119;5095.490450954912;25193.87403062991;25462.745372546222;25824.612630810367;25838.87080564604;25871;2049.6208201482773;2250.909963601454;2270.580886945659;2318.7681231876845;2371.8576885386933;13984.440622375092;14503.854961450355;14646.121232726071;14688.632784180374;15127.01674391161;1501.4894935721798;1554.440401455475;1601.1033821060244;1653.6114013206886;1765.2938824470186;101852.49073754584;109406.45296773466;111848;113710;119057;10465.84301235482;10696.304740192018;11030.22788404804;11142;11209.831852522218;14054;14093.295335233197;14101.647458813612;14107.153570785786;14111.223882686345;7362.3741981932135;8532.744017679479;8605.65577376898;8759.820984584783;8760.386772925835;6586.4401525870835;8914.33142514314;8922.68770593026;8994.820103597891;9027.45835249887;5554.638948468337;6534.771283005072;6625.834354141089;6637.9668101660145;6716.83207919806;1714.1000974488245;1795.6139430022347;1873.72830939513;1873.7751469823709;1913.7033759767257;880.4937161132247;957.683964291782;957.7844984878346;978.554757585297;1089.2756317049073;19354.129064192144;20513.384598461773;20520.794792052187;20552.691709624367;20913.89543052275;989.4904124375952;1007.4861820471613;1024.3751311699857;1034.3535290443472;1079.3523885668535;9137.040610735778;10536.473176341153;11306.886931130748;11326.32042077478;11330.263532870502;598110;599394;601687;602741;630146;70049.2995070053;95278;137030.31484842513;139250;142675;952.4951775558965;974.7027156717128;1062.134360496193;1075.1184029096223;1091.6736165558884;14158.716825663636;16506.917465412596;17578.296868125133;17733.46799596014;17835;52252.47747522628;52687.209691855416;53117.468825311254;53402;53660.46339536711;73300.26699732934;76436.23563764514;77055.45889082299;77789.61105194438;78165.60917195378;7805.141434442094;7999.880001799861;8092.231238032377;8190.140035296208;8247.505149690798;8747.30021598258;8999.785029020999;11250.66248012561;11830.763384732085;12345;10263.178945684178;10280.845787313046;10617.681469555926;10771.246012779031;10970.506327215135;10062.69811905644;10339.483025848527;10748.570057197625;10813;10824.67525974222;9310.813783724423;10746.785064298827;10990.285631433931;11112.944435277772;11226.550937962716;7962.482438641469;7999.560024198492;8076.596170191585;8129;8376.32989360862;10351;10777.73055673583;10979.945100274448;11035.503402346752;11083.390413527333 03:30:15 INFO - PROCESS | 1668 | |3;mootools.html;1422.3812641500954;1545.196497821133;1553.7358649029663;1588.8728901687862;1715.6225630361316;7849.960750196248;7932.722354717586;8785.64857405703;8787.340949428786;8798.384113112068;8464.365172612048;8704.303655707545;8709.956450217756;8711.08533603972;8712.564371781402;2078.1168003598486;2339.9766002340016;2372.7152741671025;2409.265174121892;2409.3856066703056;2422.0311875249954;2435.2329016359877;2437.1591800828687;2444.9388765280833;2445.9510809783883;2326.6277395616703;2367.881605919697;2393.56915755164;2398.7481314461907;2400.0279886645935;2105.9894700526474;2340.5318936212657;2388.22382725614;2392.342105920868;2395.4250979764906;2364.680768096302;2401.939951501209;2402.8077753779658;2402.8918698658604;2402.9759702402926;8598;8672.52301123438;8705.216530512283;8718.651253949834;8744.606492707844;5142.948570514284;5162.432132465426;5292.814751483718;5323.866903327409;5339.279197308391;3262.21706790371;3453.704860677246;3506.4740288956605;3536.0806190390426;3564.3584154852115;1033.8552602635637;1038.5015192707467;1065.0521036277719;1073.6617965340893;1082.718493191768;12006.579769708018;12039;12056.517739290417;12058.939705301418;12062.276263424135;4725.025424391773;4743.928841067387;4770.76146192689;4800.615950723934;4833.009233107205;9978.251631127694;10093.747656308504;10113.089821916063;10119.443430611387;10133.442660653587;5387.46125387466;5387.81142660005;5388.67667939917;5390.8921821563345;5449.83650490478;3821.560520540118;4514.503404625489;4757;4793.352897358812;4864.927026094611;3988.142549351903;3997.6601988831276;4065.6340929316502;4104.302268614343;4124.680102367273;1308.6782349826553;1328.7276108397755;1331.7669407853587;1342.503273788685;1348.8853447457077;862.1680078724062;938.4088024544493;946.2051876423837;948.0560866794103;974.9853752193723;5716.657000579979;5888.234529511136;5924.555658325643;5971.4327138921735;6043.516518678495;433.57509640552433;438.2440290498928;439.6131404364203;447.771636465405;458.5277164520549;3843.8846834595006;3876.3022655922205;3910.7262491625884;3935.0162459385156;3966.960330396717;10370.948145259375;10738.838917416246;10758.462076896123;10799.946000269949;10868.782624347468;10232.488375581192;10665.84001239982;10791;10793.62222322215;10798.136149108053;8098.5545794981845;8263.752087437386;8363.540005299767;8437.114103019094;8463.661453541912;5027.899442011066;5615.859603509945;5672.404397538199;5685.516731077904;5745.511631511368;9276.07239276067;9453.905460945303;9476.526173691425;9477.668281610386;9481.762955926157;9058.230050445785;9529.904700953179;9530.332876698565;9532.571034303335;9568.473733944422;6804.829879253059;6988.825279368057;7145.642717863982;7392.55644661322;7472.252774722484;8026.678932842855;8029.197080291924;8101.068377136469;8110.716124935599;8112.959435202786;7070.116235470567;7123.358897699232;7144.714211431692;7229.530080544748;7251.927480725125;6878.27778083294;6955.408790252885;7002.754903578554;7049.365557099885;7075.5047146699235;6630.23752268476;6741.157355330583;6774.966125169342;6852.897206542006;6859.828504287433;4667.299905014275;4742.549457801503;4746.02706445171;4790.640701947298;4811.7834697438;4830.203016502238;4867.318575399376;4931.5315045070665;4937.77779999908;5047.066292735855 03:30:15 INFO - PROCESS | 1668 | |4;prototype.html;312.97496200303965;316.1432517876554;325.7133722324354;326.9182704323918;331.03503287915726;6400.839979000527;6493.448056915162;6506.121673574066;6531.2489063757685;6558;5070.569001634865;6472.190976127984;6485.546011779165;6486.902696459545;6524.771632992846;4872.244802055687;4875.951240487603;4883.243097319921;4889.657723959315;4895.241237608158;4678.719276843383;4824.44518880328;4877.1952627816545;4900.754962251891;4914.606831453475;4707.223308154151;4766.594839438651;4780.378550788392;4780.426348838127;4805.519448055202;4783.976080119577;4789.305550695164;4795.088933102699;4814.855554333376;4816.1330960427285;4025.852631999866;4112.835486580533;4116.341385378355;4117.691173162025;4123.484564429446;5073.568746656501;5136.64043516954;6170.753169873245;6212.844678883019;6255.437010669016;5615.803446879339;5621.747021384048;5626.7186640668215;5628.831135065913;5636.605437619368;6649.138241292398;6949.687264073129;6950.096487456598;6963.0251764752975;6995.755148569776;4475.858656042721;4503.707259028152;4506.233940230169;4514.864554063351;4526;7254;7368.852622947565;7373.188949215582;7376.373008294301;7385;4569.405977222939;4569.908601827945;4574.08518296342;4575.588197062214;4584.381108550369;4630.675852690347;4633.351330813689;4636.629069674485;4637.072585482917;4645.512221216791;5310.9734451327495;5487.670739755627;5541.5843811714285;5553.77784888608;5567.888642227133;6911.89632155518;6969.128858892638;6991.720331186695;6992.1259842519685;7017.403520700797;1586.9682606347808;1614.8223695393497;1620.0441739373591;1621.9107949062916;1622.7160246956735;980.2060331131806;983.3165949664914;1000.0949141027381;1006.0995409108953;1016.7544757671941;6416.518761092937;6429.8714025720155;6437;6582.440492558187;6634.35110689119;7310.049693539806;7313.305236002571;7317.451191160578;7337.229590892879;7349.448791340671;5765.654060756368;6295.653739044396;6600.735970561123;6601.933980660228;6638.800835974928;3626.1659818241424;3696.5564132304294;3805.200907809391;3863.4977452931503;3876.2247550490015;7672.1560628330835;7711.074671039399;7712.730054448068;7723.729669461542;7736.690532378754;7443.255674432622;7514.586697731568;7527.887081693779;7538.3215510604305;7539.359154471822;6208.9689551551955;7688.423368247292;7690;7690.269424404784;7720.652570634223;5896.7936122235515;6901.378875901193;7239.782806515813;7252.166000909963;7293.234210407831;5659.830205093854;7058.752943646948;7102.964485177541;7151.106111736033;7174.569525828467;7645.61771911391;7646.694132234648;7649.273319034683;7650.426218033558;7661.1572726998875;4584.289435137559;4589;4589.747563884019;4590.74750888691;4594.540545945379;6260.653959398751;6957.965210173917;6974.849149890211;7231.891521627285;7238.710451581877;6257.624542527281;6436.710348034256;6940;6960.686769095504;6998.195207551197;6888.758893438906;6927.307269273032;6930.549514281555;6939.479539034492;6953.79138625842;6797.422219111233;6810.148731408574;6810.965945170243;6811.046453496416;6820.215675197416;6687.598744075176;6919.619420931897;6938.653067346511;6941.305869413019;6958.373746362851;6583.506237032146;6731.528792984444;6751.86496270062;6817;6888.724451021903 03:30:15 INFO - PROCESS | 1668 | |5;yui.html;699.3181647893304;746.7610364683303;754.1063839350373;761.1361105145658;771.2018061306545;14743.48397806077;14775.408983640655;15061.021033632805;15135.091894486324;15149.621259468518;14031.649208769786;14164.50424235152;14173.433062677506;14186.716265674706;14209.431622735105;721.9169795473535;897.4525539420948;912.7900582865911;918.6141820435399;922.2483675804232;737.2259127915667;897.0849733272091;922.9538523073853;923.8152369526088;926.2543652359848;961.2022021721954;965.8792650918635;967.1537404770826;968.404431274768;969.5636963366478;753.8529986652605;935.4667839331584;958.238200630497;962.5379817687514;963.1812958984842;707.7310621963672;708.599641202724;708.7767353283699;708.858228354326;709.4749885085;14913.179775112365;14991.17548534841;15002;15004.399824007027;15014.549563513112;12494.375281235902;12593.244405335618;12796.48814047437;12855.935720321431;12904.096713230078;9917.206623470105;10027.147692446151;10059.497025148787;10203.693889183261;10343.689689309333;3341.9331613367594;3397.9830100849586;3474.3746125697367;3495.755297129202;3514.121469632592;71547.56904861926;72462.63768681175;72508.63745681291;72572.63713681398;73554.99999999946;8091.959540202261;9373.437593744397;9504.619815207314;9554.522273886418;9773;22099.116035358405;22105.889470552545;22127.889360553418;22142.892855357168;22154.892255387167;7205;7504.287092726182;7531.171571127172;7591.810204744926;7710.267524585155;4067.044244602516;4114.897127571775;4120.1553681495225;4184.66522678185;4238.470191226097;3515.736319775976;3580.0691820126954;3581.516495273157;3584.7849129052343;3589.353916295092;609.8871708733899;627.6673363117555;629.7260691599168;638.1257676982564;647.2879832184559;542.3058485139028;549.7800879648173;564.8616089058124;582.4990508162977;602.4969150759077;8012.399070069768;8042.356611471068;8087.676492940334;8154.755357339171;8191.467554608931;495.24722421918483;528.6959998001165;558.6229295225706;590.1000973515338;614.2076721029822;3692.667659910621;4051.169510250392;4061.1674606706147;4082.6733861291027;4150.771707556113;85326.14673853306;85755;85975.57012214899;86791.1320886783;87625;71217.6439117801;71680.64159679168;73681;76198;78671.42657146826;66751.99872001924;66981.99527007098;67111;69604.6519767398;69626.30373696299;8090.231428014565;8163.428560000743;8220.671173153007;8409.915900840913;8484.660613575388;40039.59960400359;41030.9742256446;45291.54708453005;46067;48374;60536.394636053075;62019.68990155201;66539.33460665331;67290.45065918149;67732.661336693;10837.295575787548;11091.5563377464;11245.550177992789;11246;11246.60636877738;11282.94358528235;12030.27818330868;12049.277043377426;12244.87755122473;12408.255504669749;8814.735557933273;10450.425226612306;10475.266731328733;11487.655370338569;11641.41792910368;11022.50398732075;11362.375069371265;11441.199116061796;11602.825957610812;11954.462049207632;11015.228933974546;11173.162012849232;11316.26444281119;11334.773304533699;11572.884271157182;9590.08894155054;9723;9776.21790256792;9959.900400995897;9964.701058968243;9637.22902167811;9649.179819715402;9766.023397660461;9771.267154963549;9791.020897910437 03:30:15 INFO - PROCESS | 1668 | __end_tp_report 03:30:15 INFO - PROCESS | 1668 | __start_cc_report 03:30:15 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1162 03:30:15 INFO - PROCESS | 1668 | __end_cc_report 03:30:15 INFO - PROCESS | 1668 | __startTimestamp1464431415267__endTimestamp 03:30:15 INFO - PROCESS | 1668 | 03:30:15 INFO - PROCESS | 1668 | ------- Summary: start ------- 03:30:15 INFO - PROCESS | 1668 | Number of tests: 6 03:30:15 INFO - PROCESS | 1668 | 03:30:15 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5666.73 Median:5817.83 stddev:638.76 (11.0%) stddev-sans-first:602.32 03:30:15 INFO - PROCESS | 1668 | Values: 2885.9 3349.1 3425.2 3436.0 3478.8 5585.7 5591.8 5595.7 5595.8 5617.5 5565.1 5624.7 5624.9 5647.1 5697.7 5104.5 5107.5 5135.9 5200.4 5260.2 5201.9 5226.3 5236.7 5240.4 5254.1 5162.6 5187.3 5208.1 5227.6 5229.2 5214.3 5235.2 5236.8 5252.3 5279.9 5069.2 5091.6 5138.1 5169.8 5190.8 4559.3 5432.7 5506.5 5514.4 5523.6 6126.3 6175.0 6176.3 6182.6 6185.1 5273.9 6069.6 6186.3 6236.6 6243.4 5675.5 5713.8 5720.0 5723.2 5727.5 6309.3 6309.5 6310.1 6314.4 6316.6 5910.3 6009.7 6019.3 6046.3 6059.8 6146.9 6155.2 6155.9 6160.0 6165.9 6040.8 6094.2 6094.8 6102.2 6140.6 5991.0 6082.6 6097.1 6116.2 6122.0 4832.6 4850.5 4870.2 4870.9 4872.3 4335.6 4357.1 4407.5 4422.1 4435.5 5625.9 5632.2 5643.0 5643.5 5646.6 6042.8 6245.9 6257.0 6258.6 6270.7 5588.8 5612.3 5647.0 5667.8 5695.1 5344.2 5344.3 5345.1 5354.1 5362.4 6251.3 6303.4 6374.1 6375.2 6412.8 6131.3 6239.0 6242.1 6250.9 6258.2 6359.1 6372.0 6379.6 6380.4 6390.8 5714.2 6071.5 6120.6 6147.9 6160.2 6131.3 6167.1 6202.1 6211.8 6213.0 5816.7 5904.7 5957.1 5959.1 5965.3 5777.4 5927.9 5939.6 5957.5 6050.1 5848.0 5851.5 5858.9 5865.4 5868.3 5188.7 5984.8 6002.6 6003.2 6035.1 5718.7 5815.5 5817.8 5827.3 5923.2 03:30:15 INFO - PROCESS | 1668 | 03:30:15 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:62920.62 Median:36551.86 stddev:129994.72 (355.6%) stddev-sans-first:130274.94 03:30:15 INFO - PROCESS | 1668 | Values: 512.7 513.9 517.3 538.4 584.0 28599.9 37871.2 38790.2 38806.4 38873.0 37240.8 41475.6 42164.7 42200.4 42346.4 8375.4 9371.3 9601.0 9603.5 9604.2 8530.5 11381.9 11600.4 11601.9 11609.3 11330.2 11430.3 11452.4 11456.4 11496.1 8523.0 11293.5 11374.3 11409.4 11612.4 11285.9 11394.8 11410.1 11416.4 11420.0 38116.0 38594.0 38607.8 38620.6 38653.0 3120.3 3163.6 3203.7 3371.8 3702.9 28086.7 28467.9 28906.0 29243.6 29438.7 2723.3 2752.4 2759.3 2774.3 2847.3 494611.0 499377.0 499441.0 500342.5 500937.0 59315.0 60389.7 60436.7 60579.4 60601.4 63074.7 63149.4 63152.1 63357.7 63386.4 16745.0 17509.1 17510.8 17532.7 17541.7 12627.2 12906.5 12910.9 12918.8 13577.3 13824.9 15308.8 15450.2 15622.5 15651.1 5993.9 6134.8 6409.5 7057.9 7295.3 955.7 958.5 1003.5 1010.0 1016.6 23712.9 26616.7 27126.1 27365.9 27519.6 976.5 1052.4 1090.3 1131.6 1158.8 13925.7 14121.7 14176.2 14198.9 14301.1 498072.0 504236.0 504711.0 506557.0 509492.0 58616.0 58648.1 58686.0 58741.8 58837.0 1349.7 1469.7 1512.1 1513.5 1517.3 26782.7 26937.7 28780.4 28845.3 29337.3 40017.2 48150.5 48404.3 48449.5 48646.0 413304.0 416839.0 416921.0 417179.0 611420.0 37698.0 38425.0 38880.2 39462.8 39490.8 41954.6 41990.6 42113.0 43259.8 43448.3 35373.6 35603.3 36910.6 37656.2 37817.0 29723.6 36067.3 36401.6 37108.0 37247.3 36338.0 36702.1 37387.6 37983.4 38765.4 30917.5 32251.0 32336.0 32432.0 32445.5 24293.5 24458.6 24714.8 25052.2 25158.2 03:30:15 INFO - PROCESS | 1668 | 03:30:15 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:34648.62 Median:2345.31 stddev:100849.29 (4300.0%) stddev-sans-first:101099.41 03:30:15 INFO - PROCESS | 1668 | Values: 397.6 398.6 399.1 401.7 411.1 19329.9 20022.9 20595.9 20607.9 21401.5 17272.8 19979.0 20497.3 20792.0 20818.4 5720.2 5753.1 5817.4 5915.7 5919.3 5596.1 5756.2 5758.0 5805.7 5805.9 5637.3 5732.7 5743.1 5748.0 5768.0 5598.3 5707.1 5737.9 5742.7 5745.1 4985.0 5049.1 5081.7 5089.0 5095.5 25193.9 25462.7 25824.6 25838.9 25871.0 2049.6 2250.9 2270.6 2318.8 2371.9 13984.4 14503.9 14646.1 14688.6 15127.0 1501.5 1554.4 1601.1 1653.6 1765.3 101852.5 109406.5 111848.0 113710.0 119057.0 10465.8 10696.3 11030.2 11142.0 11209.8 14054.0 14093.3 14101.6 14107.2 14111.2 7362.4 8532.7 8605.7 8759.8 8760.4 6586.4 8914.3 8922.7 8994.8 9027.5 5554.6 6534.8 6625.8 6638.0 6716.8 1714.1 1795.6 1873.7 1873.8 1913.7 880.5 957.7 957.8 978.6 1089.3 19354.1 20513.4 20520.8 20552.7 20913.9 989.5 1007.5 1024.4 1034.4 1079.4 9137.0 10536.5 11306.9 11326.3 11330.3 598110.0 599394.0 601687.0 602741.0 630146.0 70049.3 95278.0 137030.3 139250.0 142675.0 952.5 974.7 1062.1 1075.1 1091.7 14158.7 16506.9 17578.3 17733.5 17835.0 52252.5 52687.2 53117.5 53402.0 53660.5 73300.3 76436.2 77055.5 77789.6 78165.6 7805.1 7999.9 8092.2 8190.1 8247.5 8747.3 8999.8 11250.7 11830.8 12345.0 10263.2 10280.8 10617.7 10771.2 10970.5 10062.7 10339.5 10748.6 10813.0 10824.7 9310.8 10746.8 10990.3 11112.9 11226.6 7962.5 7999.6 8076.6 8129.0 8376.3 10351.0 10777.7 10979.9 11035.5 11083.4 03:30:15 INFO - PROCESS | 1668 | 03:30:15 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5621.39 Median:4806.20 stddev:3174.70 (66.1%) stddev-sans-first:3167.92 03:30:15 INFO - PROCESS | 1668 | Values: 1422.4 1545.2 1553.7 1588.9 1715.6 7850.0 7932.7 8785.6 8787.3 8798.4 8464.4 8704.3 8710.0 8711.1 8712.6 2078.1 2340.0 2372.7 2409.3 2409.4 2422.0 2435.2 2437.2 2444.9 2446.0 2326.6 2367.9 2393.6 2398.7 2400.0 2106.0 2340.5 2388.2 2392.3 2395.4 2364.7 2401.9 2402.8 2402.9 2403.0 8598.0 8672.5 8705.2 8718.7 8744.6 5142.9 5162.4 5292.8 5323.9 5339.3 3262.2 3453.7 3506.5 3536.1 3564.4 1033.9 1038.5 1065.1 1073.7 1082.7 12006.6 12039.0 12056.5 12058.9 12062.3 4725.0 4743.9 4770.8 4800.6 4833.0 9978.3 10093.7 10113.1 10119.4 10133.4 5387.5 5387.8 5388.7 5390.9 5449.8 3821.6 4514.5 4757.0 4793.4 4864.9 3988.1 3997.7 4065.6 4104.3 4124.7 1308.7 1328.7 1331.8 1342.5 1348.9 862.2 938.4 946.2 948.1 975.0 5716.7 5888.2 5924.6 5971.4 6043.5 433.6 438.2 439.6 447.8 458.5 3843.9 3876.3 3910.7 3935.0 3967.0 10370.9 10738.8 10758.5 10799.9 10868.8 10232.5 10665.8 10791.0 10793.6 10798.1 8098.6 8263.8 8363.5 8437.1 8463.7 5027.9 5615.9 5672.4 5685.5 5745.5 9276.1 9453.9 9476.5 9477.7 9481.8 9058.2 9529.9 9530.3 9532.6 9568.5 6804.8 6988.8 7145.6 7392.6 7472.3 8026.7 8029.2 8101.1 8110.7 8113.0 7070.1 7123.4 7144.7 7229.5 7251.9 6878.3 6955.4 7002.8 7049.4 7075.5 6630.2 6741.2 6775.0 6852.9 6859.8 4667.3 4742.5 4746.0 4790.6 4811.8 4830.2 4867.3 4931.5 4937.8 5047.1 03:30:15 INFO - PROCESS | 1668 | 03:30:15 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:5680.16 Median:6454.60 stddev:1825.10 (28.3%) stddev-sans-first:1785.20 03:30:15 INFO - PROCESS | 1668 | Values: 313.0 316.1 325.7 326.9 331.0 6400.8 6493.4 6506.1 6531.2 6558.0 5070.6 6472.2 6485.5 6486.9 6524.8 4872.2 4876.0 4883.2 4889.7 4895.2 4678.7 4824.4 4877.2 4900.8 4914.6 4707.2 4766.6 4780.4 4780.4 4805.5 4784.0 4789.3 4795.1 4814.9 4816.1 4025.9 4112.8 4116.3 4117.7 4123.5 5073.6 5136.6 6170.8 6212.8 6255.4 5615.8 5621.7 5626.7 5628.8 5636.6 6649.1 6949.7 6950.1 6963.0 6995.8 4475.9 4503.7 4506.2 4514.9 4526.0 7254.0 7368.9 7373.2 7376.4 7385.0 4569.4 4569.9 4574.1 4575.6 4584.4 4630.7 4633.4 4636.6 4637.1 4645.5 5311.0 5487.7 5541.6 5553.8 5567.9 6911.9 6969.1 6991.7 6992.1 7017.4 1587.0 1614.8 1620.0 1621.9 1622.7 980.2 983.3 1000.1 1006.1 1016.8 6416.5 6429.9 6437.0 6582.4 6634.4 7310.0 7313.3 7317.5 7337.2 7349.4 5765.7 6295.7 6600.7 6601.9 6638.8 3626.2 3696.6 3805.2 3863.5 3876.2 7672.2 7711.1 7712.7 7723.7 7736.7 7443.3 7514.6 7527.9 7538.3 7539.4 6209.0 7688.4 7690.0 7690.3 7720.7 5896.8 6901.4 7239.8 7252.2 7293.2 5659.8 7058.8 7103.0 7151.1 7174.6 7645.6 7646.7 7649.3 7650.4 7661.2 4584.3 4589.0 4589.7 4590.7 4594.5 6260.7 6958.0 6974.8 7231.9 7238.7 6257.6 6436.7 6940.0 6960.7 6998.2 6888.8 6927.3 6930.5 6939.5 6953.8 6797.4 6810.1 6811.0 6811.0 6820.2 6687.6 6919.6 6938.7 6941.3 6958.4 6583.5 6731.5 6751.9 6817.0 6888.7 03:30:15 INFO - PROCESS | 1668 | 03:30:15 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:17509.47 Median:31323.68 stddev:23970.76 (76.5%) stddev-sans-first:24004.77 03:30:15 INFO - PROCESS | 1668 | Values: 699.3 746.8 754.1 761.1 771.2 14743.5 14775.4 15061.0 15135.1 15149.6 14031.6 14164.5 14173.4 14186.7 14209.4 721.9 897.5 912.8 918.6 922.2 737.2 897.1 923.0 923.8 926.3 961.2 965.9 967.2 968.4 969.6 753.9 935.5 958.2 962.5 963.2 707.7 708.6 708.8 708.9 709.5 14913.2 14991.2 15002.0 15004.4 15014.5 12494.4 12593.2 12796.5 12855.9 12904.1 9917.2 10027.1 10059.5 10203.7 10343.7 3341.9 3398.0 3474.4 3495.8 3514.1 71547.6 72462.6 72508.6 72572.6 73555.0 8092.0 9373.4 9504.6 9554.5 9773.0 22099.1 22105.9 22127.9 22142.9 22154.9 7205.0 7504.3 7531.2 7591.8 7710.3 4067.0 4114.9 4120.2 4184.7 4238.5 3515.7 3580.1 3581.5 3584.8 3589.4 609.9 627.7 629.7 638.1 647.3 542.3 549.8 564.9 582.5 602.5 8012.4 8042.4 8087.7 8154.8 8191.5 495.2 528.7 558.6 590.1 614.2 3692.7 4051.2 4061.2 4082.7 4150.8 85326.1 85755.0 85975.6 86791.1 87625.0 71217.6 71680.6 73681.0 76198.0 78671.4 66752.0 66982.0 67111.0 69604.7 69626.3 8090.2 8163.4 8220.7 8409.9 8484.7 40039.6 41031.0 45291.5 46067.0 48374.0 60536.4 62019.7 66539.3 67290.5 67732.7 10837.3 11091.6 11245.6 11246.0 11246.6 11282.9 12030.3 12049.3 12244.9 12408.3 8814.7 10450.4 10475.3 11487.7 11641.4 11022.5 11362.4 11441.2 11602.8 11954.5 11015.2 11173.2 11316.3 11334.8 11572.9 9590.1 9723.0 9776.2 9959.9 9964.7 9637.2 9649.2 9766.0 9771.3 9791.0 03:30:15 INFO - PROCESS | 1668 | -------- Summary: end -------- 03:30:15 INFO - PROCESS | 1668 | 03:30:15 INFO - TEST-INFO | 1668: exit 0 03:30:15 INFO - TEST-OK | dromaeo_css | took 1114981ms 03:30:15 INFO - TEST-START | kraken 03:30:15 INFO - operating with platform_type : mac_ 03:30:15 INFO - Initialising browser for kraken test... 03:30:15 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/tmpOsYFpg/profile http://localhost:49278/getInfo.html) 03:30:18 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 03:30:18 INFO - PROCESS | 1677 | colorDepth:24 03:30:18 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 03:30:18 INFO - PROCESS | 1677 | __metrics 03:30:18 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" 03:30:18 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:30:18 INFO - TEST-INFO | 1677: exit 0 03:30:18 INFO - Browser initialized. 03:30:18 INFO - Running cycle 1/1 for kraken test... 03:30:18 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/tmpOsYFpg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:30:30 INFO - PROCESS | 1679 | RSS: Main: 211181568 03:30:30 INFO - PROCESS | 1679 | 03:30:33 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 03:30:34 INFO - PROCESS | 1679 | RSS: Main: 272076800 03:30:34 INFO - PROCESS | 1679 | 03:30:37 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 03:30:37 INFO - PROCESS | 1679 | RSS: Main: 285749248 03:30:37 INFO - PROCESS | 1679 | 03:30:40 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 03:30:41 INFO - PROCESS | 1679 | RSS: Main: 374370304 03:30:41 INFO - PROCESS | 1679 | 03:30:43 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 03:30:44 INFO - PROCESS | 1679 | RSS: Main: 280276992 03:30:44 INFO - PROCESS | 1679 | 03:30:46 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:30:47 INFO - PROCESS | 1679 | RSS: Main: 280326144 03:30:47 INFO - PROCESS | 1679 | 03:30:49 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 03:30:50 INFO - PROCESS | 1679 | RSS: Main: 319102976 03:30:50 INFO - PROCESS | 1679 | 03:30:53 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 03:30:54 INFO - PROCESS | 1679 | RSS: Main: 319512576 03:30:54 INFO - PROCESS | 1679 | 03:30:56 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 03:30:57 INFO - PROCESS | 1679 | RSS: Main: 317624320 03:30:57 INFO - PROCESS | 1679 | 03:30:59 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:30:59 INFO - PROCESS | 1679 | RSS: Main: 309387264 03:30:59 INFO - PROCESS | 1679 | 03:31:01 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:31:02 INFO - PROCESS | 1679 | RSS: Main: 316194816 03:31:02 INFO - PROCESS | 1679 | 03:31:04 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:31:04 INFO - PROCESS | 1679 | RSS: Main: 319193088 03:31:04 INFO - PROCESS | 1679 | 03:31:07 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:31:08 INFO - PROCESS | 1679 | RSS: Main: 324050944 03:31:08 INFO - PROCESS | 1679 | 03:31:11 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:31:11 INFO - PROCESS | 1679 | RSS: Main: 322138112 03:31:11 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 03:31:13 INFO - PROCESS | 1679 | RSS: Main: 386113536 03:31:13 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | __start_tp_report 03:31:13 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 03:31:13 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 03:31:13 INFO - PROCESS | 1679 | |i|pagename|runs| 03:31:13 INFO - PROCESS | 1679 | |0;ai-astar;115;112;121;113;114;116;139;114;115;133 03:31:13 INFO - PROCESS | 1679 | |1;audio-beat-detection;154;123;141;167;120;141;142;118;168;116 03:31:13 INFO - PROCESS | 1679 | |2;audio-dft;186;184;187;182;184;183;183;184;187;185 03:31:13 INFO - PROCESS | 1679 | |3;audio-fft;70;78;74;154;62;79;81;92;141;55 03:31:13 INFO - PROCESS | 1679 | |4;audio-oscillator;96;95;140;77;79;86;124;80;79;126 03:31:13 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;89;87;90;130;89;86;91;86;89;87 03:31:13 INFO - PROCESS | 1679 | |6;imaging-darkroom;129;95;95;101;119;95;96;95;94;107 03:31:13 INFO - PROCESS | 1679 | |7;imaging-desaturate;84;88;84;85;84;84;84;86;85;87 03:31:13 INFO - PROCESS | 1679 | |8;json-parse-financial;61;64;64;63;64;66;64;84;61;59 03:31:13 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;63;65;64;78;72;74;73;68;69;72 03:31:13 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;81;81;81;80;82;81;82;80;82;86 03:31:13 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;131;144;139;148;128;128;128;130;147;131 03:31:13 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;168;167;167;166;171;165;163;167;167;165 03:31:13 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;66;67;68;66;68;77;92;74;57;90 03:31:13 INFO - PROCESS | 1679 | __end_tp_report 03:31:13 INFO - PROCESS | 1679 | __start_cc_report 03:31:13 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,2011 03:31:13 INFO - PROCESS | 1679 | __end_cc_report 03:31:13 INFO - PROCESS | 1679 | __startTimestamp1464431473782__endTimestamp 03:31:13 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | ------- Summary: start ------- 03:31:13 INFO - PROCESS | 1679 | Number of tests: 14 03:31:13 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:119.20 Median:115.50 stddev:9.28 (8.0%) stddev-sans-first:9.72 03:31:13 INFO - PROCESS | 1679 | Values: 115.0 112.0 121.0 113.0 114.0 116.0 139.0 114.0 115.0 133.0 03:31:13 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:141.50 stddev:19.59 (13.8%) stddev-sans-first:20.01 03:31:13 INFO - PROCESS | 1679 | Values: 154.0 123.0 141.0 167.0 120.0 141.0 142.0 118.0 168.0 116.0 03:31:13 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:184.50 Median:184.50 stddev:1.72 (0.9%) stddev-sans-first:1.73 03:31:13 INFO - PROCESS | 1679 | Values: 186.0 184.0 187.0 182.0 184.0 183.0 183.0 184.0 187.0 185.0 03:31:13 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:88.60 Median:76.00 stddev:32.82 (43.2%) stddev-sans-first:34.11 03:31:13 INFO - PROCESS | 1679 | Values: 70.0 78.0 74.0 154.0 62.0 79.0 81.0 92.0 141.0 55.0 03:31:13 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:98.20 Median:79.50 stddev:23.26 (29.3%) stddev-sans-first:24.65 03:31:13 INFO - PROCESS | 1679 | Values: 96.0 95.0 140.0 77.0 79.0 86.0 124.0 80.0 79.0 126.0 03:31:13 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:92.40 Median:89.00 stddev:13.32 (15.0%) stddev-sans-first:14.07 03:31:13 INFO - PROCESS | 1679 | Values: 89.0 87.0 90.0 130.0 89.0 86.0 91.0 86.0 89.0 87.0 03:31:13 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:102.60 Median:95.00 stddev:12.19 (12.8%) stddev-sans-first:8.38 03:31:13 INFO - PROCESS | 1679 | Values: 129.0 95.0 95.0 101.0 119.0 95.0 96.0 95.0 94.0 107.0 03:31:13 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.00 stddev:1.45 (1.7%) stddev-sans-first:1.48 03:31:13 INFO - PROCESS | 1679 | Values: 84.0 88.0 84.0 85.0 84.0 84.0 84.0 86.0 85.0 87.0 03:31:13 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:64.00 stddev:6.98 (10.9%) stddev-sans-first:7.25 03:31:13 INFO - PROCESS | 1679 | Values: 61.0 64.0 64.0 63.0 64.0 66.0 64.0 84.0 61.0 59.0 03:31:13 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:72.00 stddev:4.85 (6.7%) stddev-sans-first:4.48 03:31:13 INFO - PROCESS | 1679 | Values: 63.0 65.0 64.0 78.0 72.0 74.0 73.0 68.0 69.0 72.0 03:31:13 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:81.50 stddev:1.71 (2.1%) stddev-sans-first:1.80 03:31:13 INFO - PROCESS | 1679 | Values: 81.0 81.0 81.0 80.0 82.0 81.0 82.0 80.0 82.0 86.0 03:31:13 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:135.40 Median:135.00 stddev:8.25 (6.1%) stddev-sans-first:8.59 03:31:13 INFO - PROCESS | 1679 | Values: 131.0 144.0 139.0 148.0 128.0 128.0 128.0 130.0 147.0 131.0 03:31:13 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:167.00 stddev:2.12 (1.3%) stddev-sans-first:2.19 03:31:13 INFO - PROCESS | 1679 | Values: 168.0 167.0 167.0 166.0 171.0 165.0 163.0 167.0 167.0 165.0 03:31:13 INFO - PROCESS | 1679 | 03:31:13 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:71.00 stddev:11.08 (15.6%) stddev-sans-first:11.50 03:31:13 INFO - PROCESS | 1679 | Values: 66.0 67.0 68.0 66.0 68.0 77.0 92.0 74.0 57.0 90.0 03:31:13 INFO - PROCESS | 1679 | -------- Summary: end -------- 03:31:13 INFO - PROCESS | 1679 | 03:31:14 INFO - TEST-INFO | 1679: exit 0 03:31:14 INFO - TEST-OK | kraken | took 58774ms 03:31:14 INFO - SUITE-END | took 1173s 03:31:14 INFO - Completed test suite (00:19:34) 03:31:14 INFO - javascript benchmark 03:31:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2885.9278518037045, 3349.1124851914237, 3425.1950791563995, 3435.982820085899, 3478.7564870459078, 5585.692786896719, 5591.776328946848, 5595.720213989304, 5595.804146854861, 5617.494425501709, 5565.053940830068, 5624.690642014688, 5624.943750562493, 5647.068233741438, 5697.743601537931, 5104.464031276718, 5107.4892510749, 5135.922961155586, 5200.375954885419, 5260.184671375943, 5201.86995325118, 5226.294450249225, 5236.73816309183, 5240.397354304247, 5254.106801843695, 5162.561182299509, 5187.273781670569, 5208.0885844977165, 5227.555657769075, 5229.2156176573435, 5214.296070030554, 5235.188545775372, 5236.81671141512, 5252.317198764136, 5279.894402111936, 5069.188929771219, 5091.567216786578, 5138.100832354323, 5169.793208271664, 5190.792368305263, 4559.293309537027, 5432.6740395576, 5506.504414602665, 5514.420985796514, 5523.613347065707, 6126.264848218198, 6175.01199808033, 6176.289726681444, 6182.567220294582, 6185.103160041769, 5273.91884663643, 6069.6054756441135, 6186.319504854462, 6236.625802451822, 6243.406876346739, 5675.5459563234845, 5713.771449142029, 5720.0, 5723.227364305849, 5727.484526392603, 6309.274433440213, 6309.463695585835, 6310.148130002391, 6314.400131987454, 6316.557840951089, 5910.34986151523, 6009.729562169713, 6019.277686677539, 6046.3349031606485, 6059.818205453844, 6146.907796383058, 6155.230596175478, 6155.876882462415, 6160.014397696347, 6165.907511387333, 6040.788572399945, 6094.240942309475, 6094.781043791259, 6102.176206212104, 6140.600860944024, 5991.0, 6082.60463069899, 6097.115918191837, 6116.235470566179, 6122.0, 4832.565069143794, 4850.5149485051215, 4870.245112007645, 4870.8782280442565, 4872.293517439952, 4335.566443355702, 4357.128574285156, 4407.471103467605, 4422.137683151819, 4435.512093669694, 5625.915611265835, 5632.155176723524, 5643.012472817322, 5643.463870932255, 5646.632968857011, 6042.758289668452, 6245.875082498415, 6256.968715156395, 6258.593191442542, 6270.749170033238, 5588.832335029955, 5612.270404847343, 5647.040003199466, 5667.829965101054, 5695.145728140729, 5344.171653393731, 5344.331958505187, 5345.144776835766, 5354.063038968165, 5362.383325917492, 6251.281102673069, 6303.432691057827, 6374.107624932606, 6375.171227740457, 6412.775552855627, 6131.284916201153, 6238.968805155945, 6242.126102345766, 6250.937490625036, 6258.155149055002, 6359.141515895295, 6372.0, 6379.649119298298, 6380.425761681286, 6390.7763228286785, 5714.200011998407, 6071.453569178795, 6120.571559990936, 6147.907781383194, 6160.229971253593, 6131.254333328352, 6167.1366008759705, 6202.131701561875, 6211.81364559064, 6213.0, 5816.680082595329, 5904.734286957011, 5957.136215248851, 5959.135925290895, 5965.343812180743, 5777.3933736957015, 5927.851803704942, 5939.554533409925, 5957.463828255478, 6050.092486127116, 5848.0350742127075, 5851.502622277154, 5858.8868115058, 5865.442782935615, 5868.2664666916635, 5188.662736922088, 5984.760609575568, 6002.609830361012, 6003.159557661844, 6035.094735789491, 5718.656880587178, 5815.505682017076, 5817.825465236049, 5827.329857066492, 5923.200367950271], "value": 5625.847735573047, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.6821370750133, 513.8818071843476, 517.2783966366918, 538.3620409814371, 583.9692941957449, 28599.857000714994, 37871.24257514848, 38790.224195516144, 38806.41790373147, 38873.02817429562, 37240.81379593098, 41475.58524414755, 42164.73505794824, 42200.36699449511, 42346.36480452788, 8375.41372103953, 9371.25029997602, 9601.0, 9603.519824008807, 9604.183644390236, 8530.488170709747, 11381.886181138207, 11600.361980091091, 11601.883981160165, 11609.303441793478, 11330.150238732087, 11430.314181149157, 11452.427378631035, 11456.427178641075, 11496.137789665767, 8523.0, 11293.548258069668, 11374.31754094757, 11409.429528523582, 11612.419379031098, 11285.943570282096, 11394.772104557946, 11410.144239182095, 11416.429178541122, 11420.0, 38116.0, 38594.03514912121, 38607.80696096501, 38620.61379386198, 38653.0, 3120.3135310231714, 3163.572917656133, 3203.727683146912, 3371.8482668279985, 3702.8518859245596, 28086.719132808616, 28467.857660711772, 28906.0, 29243.56134657982, 29438.675259613156, 2723.2647185260066, 2752.4357506711076, 2759.296379423234, 2774.3064233941514, 2847.330877243846, 494611.0, 499376.9999999964, 499441.0, 500342.4982875135, 500937.0, 59315.0, 60389.69805150946, 60436.697816511514, 60579.394206058256, 60601.39398605958, 63074.68462657657, 63149.36850631435, 63152.05271920833, 63357.683211583644, 63386.366136338045, 16745.0, 17509.124543773014, 17510.824891751174, 17532.737008944878, 17541.736873946902, 12627.242365458102, 12906.483740650456, 12910.870891290966, 12918.806217906738, 13577.253251071088, 13824.861751382357, 15308.846911530742, 15450.150241736812, 15622.53132406007, 15651.060936343856, 5993.850153746192, 6134.834381467507, 6409.455196308273, 7057.917914215319, 7295.270472952769, 955.7180631713522, 958.5446912716543, 1003.5484032185545, 1009.9798004040025, 1016.6035246253966, 23712.881435592713, 26616.733832661426, 27126.05058822932, 27365.86317068402, 27519.58720619192, 976.511744127936, 1052.400131924811, 1090.345792524479, 1131.61942430235, 1158.7972104881612, 13925.658743641865, 14121.717565648629, 14176.191803735705, 14198.929005354907, 14301.070430421989, 498072.0, 504236.0, 504711.0, 506557.0, 509492.0, 58616.0, 58648.120278195864, 58686.0, 58741.82516349734, 58837.0, 1349.709812390321, 1469.6840179361488, 1512.0625212368182, 1513.4551561437654, 1517.3020410611243, 26782.732172678025, 26937.73062269352, 28780.434653406268, 28845.27886802847, 29337.266568335963, 40017.1996560073, 48150.518494814605, 48404.273935891695, 48449.51550484591, 48646.0, 413304.0, 416839.0, 416921.0, 417179.0, 611420.0, 37698.0, 38425.0, 38880.2223955525, 39462.80268598639, 39490.802545988234, 41954.580454196286, 41990.58009419867, 42113.0, 43259.783701081295, 43448.34827477654, 35373.64626353807, 35603.28793424065, 36910.63089369072, 37656.24687506289, 37817.0, 29723.554146687384, 36067.27865442729, 36401.635983639826, 37108.0, 37247.255054899295, 36338.0, 36702.082447937944, 37387.6261237395, 37983.430248545745, 38765.41851872168, 30917.536236956013, 32251.0, 32336.029919102464, 32432.0, 32445.51331729979, 24293.51412971766, 24458.633120503564, 24714.752852471964, 25052.248432547054, 25158.24525264245], "value": 19605.553857102233, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.5944536572696, 398.5735263268304, 399.0741479766941, 401.6585901983313, 411.1078958659708, 19329.90335048323, 20022.89988550057, 20595.897020514894, 20607.896960515212, 21401.46496337592, 17272.827271727278, 19979.00104994748, 20497.282595109173, 20791.960401979915, 20818.375448736562, 5720.1991721159175, 5753.079507278836, 5817.360090390053, 5915.73379197935, 5919.319278282988, 5596.132599447093, 5756.222909907151, 5758.0, 5805.680687562182, 5805.883882322351, 5637.3235211774645, 5732.684702341369, 5743.052396354597, 5748.0, 5768.019436695751, 5598.300212473441, 5707.058335374658, 5737.913931291013, 5742.6841523716175, 5745.052066409058, 4985.002999400098, 5049.065922804256, 5081.695098294114, 5089.033083714119, 5095.490450954912, 25193.87403062991, 25462.745372546222, 25824.612630810367, 25838.87080564604, 25871.0, 2049.6208201482773, 2250.909963601454, 2270.580886945659, 2318.7681231876845, 2371.8576885386933, 13984.440622375092, 14503.854961450355, 14646.121232726071, 14688.632784180374, 15127.01674391161, 1501.4894935721798, 1554.440401455475, 1601.1033821060244, 1653.6114013206886, 1765.2938824470186, 101852.49073754584, 109406.45296773466, 111848.0, 113710.0, 119057.0, 10465.84301235482, 10696.304740192018, 11030.22788404804, 11142.0, 11209.831852522218, 14054.0, 14093.295335233197, 14101.647458813612, 14107.153570785786, 14111.223882686345, 7362.3741981932135, 8532.744017679479, 8605.65577376898, 8759.820984584783, 8760.386772925835, 6586.4401525870835, 8914.33142514314, 8922.68770593026, 8994.820103597891, 9027.45835249887, 5554.638948468337, 6534.771283005072, 6625.834354141089, 6637.9668101660145, 6716.83207919806, 1714.1000974488245, 1795.6139430022347, 1873.72830939513, 1873.7751469823709, 1913.7033759767257, 880.4937161132247, 957.683964291782, 957.7844984878346, 978.554757585297, 1089.2756317049073, 19354.129064192144, 20513.384598461773, 20520.794792052187, 20552.691709624367, 20913.89543052275, 989.4904124375952, 1007.4861820471613, 1024.3751311699857, 1034.3535290443472, 1079.3523885668535, 9137.040610735778, 10536.473176341153, 11306.886931130748, 11326.32042077478, 11330.263532870502, 598110.0, 599394.0, 601687.0, 602741.0, 630146.0, 70049.2995070053, 95278.0, 137030.31484842513, 139250.0, 142675.0, 952.4951775558965, 974.7027156717128, 1062.134360496193, 1075.1184029096223, 1091.6736165558884, 14158.716825663636, 16506.917465412596, 17578.296868125133, 17733.46799596014, 17835.0, 52252.47747522628, 52687.209691855416, 53117.468825311254, 53402.0, 53660.46339536711, 73300.26699732934, 76436.23563764514, 77055.45889082299, 77789.61105194438, 78165.60917195378, 7805.141434442094, 7999.880001799861, 8092.231238032377, 8190.140035296208, 8247.505149690798, 8747.30021598258, 8999.785029020999, 11250.66248012561, 11830.763384732085, 12345.0, 10263.178945684178, 10280.845787313046, 10617.681469555926, 10771.246012779031, 10970.506327215135, 10062.69811905644, 10339.483025848527, 10748.570057197625, 10813.0, 10824.67525974222, 9310.813783724423, 10746.785064298827, 10990.285631433931, 11112.944435277772, 11226.550937962716, 7962.482438641469, 7999.560024198492, 8076.596170191585, 8129.0, 8376.32989360862, 10351.0, 10777.73055673583, 10979.945100274448, 11035.503402346752, 11083.390413527333], "value": 9523.87302447459, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.3812641500954, 1545.196497821133, 1553.7358649029663, 1588.8728901687862, 1715.6225630361316, 7849.960750196248, 7932.722354717586, 8785.64857405703, 8787.340949428786, 8798.384113112068, 8464.365172612048, 8704.303655707545, 8709.956450217756, 8711.08533603972, 8712.564371781402, 2078.1168003598486, 2339.9766002340016, 2372.7152741671025, 2409.265174121892, 2409.3856066703056, 2422.0311875249954, 2435.2329016359877, 2437.1591800828687, 2444.9388765280833, 2445.9510809783883, 2326.6277395616703, 2367.881605919697, 2393.56915755164, 2398.7481314461907, 2400.0279886645935, 2105.9894700526474, 2340.5318936212657, 2388.22382725614, 2392.342105920868, 2395.4250979764906, 2364.680768096302, 2401.939951501209, 2402.8077753779658, 2402.8918698658604, 2402.9759702402926, 8598.0, 8672.52301123438, 8705.216530512283, 8718.651253949834, 8744.606492707844, 5142.948570514284, 5162.432132465426, 5292.814751483718, 5323.866903327409, 5339.279197308391, 3262.21706790371, 3453.704860677246, 3506.4740288956605, 3536.0806190390426, 3564.3584154852115, 1033.8552602635637, 1038.5015192707467, 1065.0521036277719, 1073.6617965340893, 1082.718493191768, 12006.579769708018, 12039.0, 12056.517739290417, 12058.939705301418, 12062.276263424135, 4725.025424391773, 4743.928841067387, 4770.76146192689, 4800.615950723934, 4833.009233107205, 9978.251631127694, 10093.747656308504, 10113.089821916063, 10119.443430611387, 10133.442660653587, 5387.46125387466, 5387.81142660005, 5388.67667939917, 5390.8921821563345, 5449.83650490478, 3821.560520540118, 4514.503404625489, 4757.0, 4793.352897358812, 4864.927026094611, 3988.142549351903, 3997.6601988831276, 4065.6340929316502, 4104.302268614343, 4124.680102367273, 1308.6782349826553, 1328.7276108397755, 1331.7669407853587, 1342.503273788685, 1348.8853447457077, 862.1680078724062, 938.4088024544493, 946.2051876423837, 948.0560866794103, 974.9853752193723, 5716.657000579979, 5888.234529511136, 5924.555658325643, 5971.4327138921735, 6043.516518678495, 433.57509640552433, 438.2440290498928, 439.6131404364203, 447.771636465405, 458.5277164520549, 3843.8846834595006, 3876.3022655922205, 3910.7262491625884, 3935.0162459385156, 3966.960330396717, 10370.948145259375, 10738.838917416246, 10758.462076896123, 10799.946000269949, 10868.782624347468, 10232.488375581192, 10665.84001239982, 10791.0, 10793.62222322215, 10798.136149108053, 8098.5545794981845, 8263.752087437386, 8363.540005299767, 8437.114103019094, 8463.661453541912, 5027.899442011066, 5615.859603509945, 5672.404397538199, 5685.516731077904, 5745.511631511368, 9276.07239276067, 9453.905460945303, 9476.526173691425, 9477.668281610386, 9481.762955926157, 9058.230050445785, 9529.904700953179, 9530.332876698565, 9532.571034303335, 9568.473733944422, 6804.829879253059, 6988.825279368057, 7145.642717863982, 7392.55644661322, 7472.252774722484, 8026.678932842855, 8029.197080291924, 8101.068377136469, 8110.716124935599, 8112.959435202786, 7070.116235470567, 7123.358897699232, 7144.714211431692, 7229.530080544748, 7251.927480725125, 6878.27778083294, 6955.408790252885, 7002.754903578554, 7049.365557099885, 7075.5047146699235, 6630.23752268476, 6741.157355330583, 6774.966125169342, 6852.897206542006, 6859.828504287433, 4667.299905014275, 4742.549457801503, 4746.02706445171, 4790.640701947298, 4811.7834697438, 4830.203016502238, 4867.318575399376, 4931.5315045070665, 4937.77779999908, 5047.066292735855], "value": 4454.984044847573, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.97496200303965, 316.1432517876554, 325.7133722324354, 326.9182704323918, 331.03503287915726, 6400.839979000527, 6493.448056915162, 6506.121673574066, 6531.2489063757685, 6558.0, 5070.569001634865, 6472.190976127984, 6485.546011779165, 6486.902696459545, 6524.771632992846, 4872.244802055687, 4875.951240487603, 4883.243097319921, 4889.657723959315, 4895.241237608158, 4678.719276843383, 4824.44518880328, 4877.1952627816545, 4900.754962251891, 4914.606831453475, 4707.223308154151, 4766.594839438651, 4780.378550788392, 4780.426348838127, 4805.519448055202, 4783.976080119577, 4789.305550695164, 4795.088933102699, 4814.855554333376, 4816.1330960427285, 4025.852631999866, 4112.835486580533, 4116.341385378355, 4117.691173162025, 4123.484564429446, 5073.568746656501, 5136.64043516954, 6170.753169873245, 6212.844678883019, 6255.437010669016, 5615.803446879339, 5621.747021384048, 5626.7186640668215, 5628.831135065913, 5636.605437619368, 6649.138241292398, 6949.687264073129, 6950.096487456598, 6963.0251764752975, 6995.755148569776, 4475.858656042721, 4503.707259028152, 4506.233940230169, 4514.864554063351, 4526.0, 7254.0, 7368.852622947565, 7373.188949215582, 7376.373008294301, 7385.0, 4569.405977222939, 4569.908601827945, 4574.08518296342, 4575.588197062214, 4584.381108550369, 4630.675852690347, 4633.351330813689, 4636.629069674485, 4637.072585482917, 4645.512221216791, 5310.9734451327495, 5487.670739755627, 5541.5843811714285, 5553.77784888608, 5567.888642227133, 6911.89632155518, 6969.128858892638, 6991.720331186695, 6992.1259842519685, 7017.403520700797, 1586.9682606347808, 1614.8223695393497, 1620.0441739373591, 1621.9107949062916, 1622.7160246956735, 980.2060331131806, 983.3165949664914, 1000.0949141027381, 1006.0995409108953, 1016.7544757671941, 6416.518761092937, 6429.8714025720155, 6437.0, 6582.440492558187, 6634.35110689119, 7310.049693539806, 7313.305236002571, 7317.451191160578, 7337.229590892879, 7349.448791340671, 5765.654060756368, 6295.653739044396, 6600.735970561123, 6601.933980660228, 6638.800835974928, 3626.1659818241424, 3696.5564132304294, 3805.200907809391, 3863.4977452931503, 3876.2247550490015, 7672.1560628330835, 7711.074671039399, 7712.730054448068, 7723.729669461542, 7736.690532378754, 7443.255674432622, 7514.586697731568, 7527.887081693779, 7538.3215510604305, 7539.359154471822, 6208.9689551551955, 7688.423368247292, 7690.0, 7690.269424404784, 7720.652570634223, 5896.7936122235515, 6901.378875901193, 7239.782806515813, 7252.166000909963, 7293.234210407831, 5659.830205093854, 7058.752943646948, 7102.964485177541, 7151.106111736033, 7174.569525828467, 7645.61771911391, 7646.694132234648, 7649.273319034683, 7650.426218033558, 7661.1572726998875, 4584.289435137559, 4589.0, 4589.747563884019, 4590.74750888691, 4594.540545945379, 6260.653959398751, 6957.965210173917, 6974.849149890211, 7231.891521627285, 7238.710451581877, 6257.624542527281, 6436.710348034256, 6940.0, 6960.686769095504, 6998.195207551197, 6888.758893438906, 6927.307269273032, 6930.549514281555, 6939.479539034492, 6953.79138625842, 6797.422219111233, 6810.148731408574, 6810.965945170243, 6811.046453496416, 6820.215675197416, 6687.598744075176, 6919.619420931897, 6938.653067346511, 6941.305869413019, 6958.373746362851, 6583.506237032146, 6731.528792984444, 6751.86496270062, 6817.0, 6888.724451021903], "value": 5066.4542141193315, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.3181647893304, 746.7610364683303, 754.1063839350373, 761.1361105145658, 771.2018061306545, 14743.48397806077, 14775.408983640655, 15061.021033632805, 15135.091894486324, 15149.621259468518, 14031.649208769786, 14164.50424235152, 14173.433062677506, 14186.716265674706, 14209.431622735105, 721.9169795473535, 897.4525539420948, 912.7900582865911, 918.6141820435399, 922.2483675804232, 737.2259127915667, 897.0849733272091, 922.9538523073853, 923.8152369526088, 926.2543652359848, 961.2022021721954, 965.8792650918635, 967.1537404770826, 968.404431274768, 969.5636963366478, 753.8529986652605, 935.4667839331584, 958.238200630497, 962.5379817687514, 963.1812958984842, 707.7310621963672, 708.599641202724, 708.7767353283699, 708.858228354326, 709.4749885085, 14913.179775112365, 14991.17548534841, 15002.0, 15004.399824007027, 15014.549563513112, 12494.375281235902, 12593.244405335618, 12796.48814047437, 12855.935720321431, 12904.096713230078, 9917.206623470105, 10027.147692446151, 10059.497025148787, 10203.693889183261, 10343.689689309333, 3341.9331613367594, 3397.9830100849586, 3474.3746125697367, 3495.755297129202, 3514.121469632592, 71547.56904861926, 72462.63768681175, 72508.63745681291, 72572.63713681398, 73554.99999999946, 8091.959540202261, 9373.437593744397, 9504.619815207314, 9554.522273886418, 9773.0, 22099.116035358405, 22105.889470552545, 22127.889360553418, 22142.892855357168, 22154.892255387167, 7205.0, 7504.287092726182, 7531.171571127172, 7591.810204744926, 7710.267524585155, 4067.044244602516, 4114.897127571775, 4120.1553681495225, 4184.66522678185, 4238.470191226097, 3515.736319775976, 3580.0691820126954, 3581.516495273157, 3584.7849129052343, 3589.353916295092, 609.8871708733899, 627.6673363117555, 629.7260691599168, 638.1257676982564, 647.2879832184559, 542.3058485139028, 549.7800879648173, 564.8616089058124, 582.4990508162977, 602.4969150759077, 8012.399070069768, 8042.356611471068, 8087.676492940334, 8154.755357339171, 8191.467554608931, 495.24722421918483, 528.6959998001165, 558.6229295225706, 590.1000973515338, 614.2076721029822, 3692.667659910621, 4051.169510250392, 4061.1674606706147, 4082.6733861291027, 4150.771707556113, 85326.14673853306, 85755.0, 85975.57012214899, 86791.1320886783, 87625.0, 71217.6439117801, 71680.64159679168, 73681.0, 76198.0, 78671.42657146826, 66751.99872001924, 66981.99527007098, 67111.0, 69604.6519767398, 69626.30373696299, 8090.231428014565, 8163.428560000743, 8220.671173153007, 8409.915900840913, 8484.660613575388, 40039.59960400359, 41030.9742256446, 45291.54708453005, 46067.0, 48374.0, 60536.394636053075, 62019.68990155201, 66539.33460665331, 67290.45065918149, 67732.661336693, 10837.295575787548, 11091.5563377464, 11245.550177992789, 11246.0, 11246.60636877738, 11282.94358528235, 12030.27818330868, 12049.277043377426, 12244.87755122473, 12408.255504669749, 8814.735557933273, 10450.425226612306, 10475.266731328733, 11487.655370338569, 11641.41792910368, 11022.50398732075, 11362.375069371265, 11441.199116061796, 11602.825957610812, 11954.462049207632, 11015.228933974546, 11173.162012849232, 11316.26444281119, 11334.773304533699, 11572.884271157182, 9590.08894155054, 9723.0, 9776.21790256792, 9959.900400995897, 9964.701058968243, 9637.22902167811, 9649.179819715402, 9766.023397660461, 9771.267154963549, 9791.020897910437], "value": 6810.640094890935, "unit": "score"}], "name": "dromaeo_css", "value": 7379.488871461528, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 112.0, 121.0, 113.0, 114.0, 116.0, 139.0, 114.0, 115.0, 133.0], "value": 119.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 123.0, 141.0, 167.0, 120.0, 141.0, 142.0, 118.0, 168.0, 116.0], "value": 139.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 187.0, 182.0, 184.0, 183.0, 183.0, 184.0, 187.0, 185.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 78.0, 74.0, 154.0, 62.0, 79.0, 81.0, 92.0, 141.0, 55.0], "value": 88.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 140.0, 77.0, 79.0, 86.0, 124.0, 80.0, 79.0, 126.0], "value": 98.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 130.0, 89.0, 86.0, 91.0, 86.0, 89.0, 87.0], "value": 92.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 95.0, 95.0, 101.0, 119.0, 95.0, 96.0, 95.0, 94.0, 107.0], "value": 102.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 88.0, 84.0, 85.0, 84.0, 84.0, 84.0, 86.0, 85.0, 87.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 64.0, 63.0, 64.0, 66.0, 64.0, 84.0, 61.0, 59.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 64.0, 78.0, 72.0, 74.0, 73.0, 68.0, 69.0, 72.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 81.0, 80.0, 82.0, 81.0, 82.0, 80.0, 82.0, 86.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 144.0, 139.0, 148.0, 128.0, 128.0, 128.0, 130.0, 147.0, 131.0], "value": 135.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 167.0, 166.0, 171.0, 165.0, 163.0, 167.0, 167.0, 165.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 66.0, 68.0, 77.0, 92.0, 74.0, 57.0, 90.0], "value": 72.5, "unit": "score"}], "name": "kraken", "value": 1500.5, "alertThreshold": 2.0}]} 03:31:15 INFO - Return code: 0 03:31:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:31:15 ERROR - # TBPL SUCCESS # 03:31:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:31:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:31:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:31:15 INFO - 2016-05-28 03:31:15.084 screenresolution[1683:9968] starting screenresolution argv=screenresolution get 03:31:15 INFO - 2016-05-28 03:31:15.087 screenresolution[1683:9968] Display 0: 1600x1200x32@60 03:31:15 INFO - 2016-05-28 03:31:15.093 screenresolution[1684:9971] starting screenresolution argv=screenresolution list 03:31:15 INFO - Available Modes on Display 0 03:31:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:31:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:31:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:31:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:31:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:31:15 INFO - Intel Iris: 03:31:15 INFO - Chipset Model: Intel Iris 03:31:15 INFO - Type: GPU 03:31:15 INFO - Bus: Built-In 03:31:15 INFO - VRAM (Dynamic, Max): 1536 MB 03:31:15 INFO - Vendor: Intel (0x8086) 03:31:15 INFO - Device ID: 0x0a2e 03:31:15 INFO - Revision ID: 0x0009 03:31:15 INFO - Displays: 03:31:15 INFO - Display: 03:31:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:31:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:31:15 INFO - Display Serial Number: 1600x1200 60 03:31:15 INFO - Main Display: Yes 03:31:15 INFO - Mirror: Off 03:31:15 INFO - Online: Yes 03:31:15 INFO - Rotation: Supported 03:31:15 INFO - Return code: 0 03:31:15 INFO - Running post-action listener: _resource_record_post_action 03:31:15 INFO - Running post-run listener: _resource_record_post_run 03:31:16 INFO - Total resource usage - Wall time: 1208s; CPU: 52.0%; Read bytes: 32808448; Write bytes: 654889472; Read time: 326; Write time: 1892 03:31:16 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 351035904; Write bytes: 346473984; Read time: 27436; Write time: 645 03:31:16 INFO - run-tests - Wall time: 1176s; CPU: 52.0%; Read bytes: 31022592; Write bytes: 297622528; Read time: 308; Write time: 1212 03:31:16 INFO - Running post-run listener: _upload_blobber_files 03:31:16 INFO - Blob upload gear active. 03:31:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:31:16 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:31:16 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'] 03:31:16 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 03:31:16 INFO - (blobuploader) - INFO - Open directory for files ... 03:31:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:31:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:31:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:31:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:31:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:31:17 INFO - (blobuploader) - INFO - Done attempting. 03:31:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:31:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:31:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:31:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:31:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:31:18 INFO - (blobuploader) - INFO - Done attempting. 03:31:18 INFO - (blobuploader) - INFO - Iteration through files over. 03:31:18 INFO - Return code: 0 03:31:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:31:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:31:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/99d9c7c0c4af5d5c19344552a43b2e1b567b5883b4da44e37aa970dcd9701192b80b0e654a6bffa723013eae3439e2241914083a4fe5cf465fed3653cd0c0fd3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/382c59b9d19aa54df4bb2e5f7ee12b94e913937bdacdaad7627ae6a99a44c7a5f9c1a39b00925d390ca42f20f7ed9cb84f2fef86539dcc946913d62d9c0bf4b2"} 03:31:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:31:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:31:18 INFO - Contents: 03:31:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/99d9c7c0c4af5d5c19344552a43b2e1b567b5883b4da44e37aa970dcd9701192b80b0e654a6bffa723013eae3439e2241914083a4fe5cf465fed3653cd0c0fd3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/382c59b9d19aa54df4bb2e5f7ee12b94e913937bdacdaad7627ae6a99a44c7a5f9c1a39b00925d390ca42f20f7ed9cb84f2fef86539dcc946913d62d9c0bf4b2"} 03:31:18 INFO - Running post-run listener: copy_logs_to_upload_dir 03:31:18 INFO - Copying logs to upload dir... 03:31:18 INFO - mkdir: /builds/slave/test/build/upload/logs 03:31:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.290016 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-05-28 03:31:18.500608) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 03:31:18.504198) ========= 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.XbVfTbkUvw/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.1jVBotWv6e/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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/99d9c7c0c4af5d5c19344552a43b2e1b567b5883b4da44e37aa970dcd9701192b80b0e654a6bffa723013eae3439e2241914083a4fe5cf465fed3653cd0c0fd3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/382c59b9d19aa54df4bb2e5f7ee12b94e913937bdacdaad7627ae6a99a44c7a5f9c1a39b00925d390ca42f20f7ed9cb84f2fef86539dcc946913d62d9c0bf4b2"} build_url:https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011769 build_url: 'https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/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/99d9c7c0c4af5d5c19344552a43b2e1b567b5883b4da44e37aa970dcd9701192b80b0e654a6bffa723013eae3439e2241914083a4fe5cf465fed3653cd0c0fd3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/382c59b9d19aa54df4bb2e5f7ee12b94e913937bdacdaad7627ae6a99a44c7a5f9c1a39b00925d390ca42f20f7ed9cb84f2fef86539dcc946913d62d9c0bf4b2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 03:31:18.542349) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:31:18.542923) ========= 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.XbVfTbkUvw/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.1jVBotWv6e/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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.005397 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:31:18.593363) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 03:31:18.593884) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 03:31:18.594431) ========= ========= Total master_lag: 0.18 =========