builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0078 starttime: 1464430191.37 results: success (0) buildid: 20160525183450 builduid: 63f91898e679451db7d589c242ad058b revision: 33693d8bdec17f07fc460ca7cc908ade88f97b86 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:51.366997) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:51.367396) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:51.367719) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:51.437654) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:51.437949) ========= 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.xqpFaTIj1k/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.jleJk77zaK/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 207M=0s 2016-05-28 03:09:51 (207 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.168361 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:51.629822) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:51.630100) ========= 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.xqpFaTIj1k/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.jleJk77zaK/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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.033086 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:51.682180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-28 03:09:51.682497) ========= 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.xqpFaTIj1k/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.jleJk77zaK/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:51,753 truncating revision to first 12 chars 2016-05-28 03:09:51,753 Setting DEBUG logging. 2016-05-28 03:09:51,753 attempt 1/10 2016-05-28 03:09:51,753 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:53,358 unpacking tar archive at: mozilla-aurora-33693d8bdec1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.863801 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-28 03:09:53.565894) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:53.566201) ========= script_repo_revision: 33693d8bdec17f07fc460ca7cc908ade88f97b86 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:53.566611) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:53.566878) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 03:09:53.582728) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-28 03:09:53.583011) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.xqpFaTIj1k/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.jleJk77zaK/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:53 INFO - MultiFileLogger online at 20160528 03:09:53 in /builds/slave/test 03:09:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:09:53 INFO - {'append_to_log': False, 03:09:53 INFO - 'base_work_dir': '/builds/slave/test', 03:09:53 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:09:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:09:53 INFO - 'branch': 'Mozilla-Aurora', 03:09:53 INFO - 'buildbot_json_path': 'buildprops.json', 03:09:53 INFO - 'config_files': ('talos/mac_config.py',), 03:09:53 INFO - 'default_actions': ('clobber', 03:09:53 INFO - 'read-buildbot-config', 03:09:53 INFO - 'download-and-extract', 03:09:53 INFO - 'populate-webroot', 03:09:53 INFO - 'create-virtualenv', 03:09:53 INFO - 'install', 03:09:53 INFO - 'run-tests'), 03:09:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:09:53 INFO - 'download_minidump_stackwalk': True, 03:09:53 INFO - 'download_symbols': 'ondemand', 03:09:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:09:53 INFO - 'tooltool.py': '/tools/tooltool.py', 03:09:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:09:53 INFO - '/tools/misc-python/virtualenv.py')}, 03:09:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:09:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:09:53 INFO - 'installer_path': 'installer.exe', 03:09:53 INFO - 'log_level': 'info', 03:09:53 INFO - 'log_name': 'talos', 03:09:53 INFO - 'log_to_console': True, 03:09:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:09:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:09:53 INFO - 'opt_config_files': (), 03:09:53 INFO - 'pip_index': False, 03:09:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:09:53 INFO - 'cmd': ('bash', 03:09:53 INFO - '-c', 03:09:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:09:53 INFO - 'enabled': True, 03:09:53 INFO - 'halt_on_failure': False, 03:09:53 INFO - 'name': 'check_screen_resolution'},), 03:09:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:09:53 INFO - 'cmd': ('bash', 03:09:53 INFO - '-c', 03:09:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:09:53 INFO - 'enabled': True, 03:09:53 INFO - 'halt_on_failure': False, 03:09:53 INFO - 'name': 'check_screen_resolution'},), 03:09:53 INFO - 'run_cmd_checks_enabled': True, 03:09:53 INFO - 'sps_profile': False, 03:09:53 INFO - 'sps_profile_interval': 0, 03:09:53 INFO - 'suite': 'dromaeojs-e10s', 03:09:53 INFO - 'system_bits': '32', 03:09:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:09:53 INFO - 'title': 't-yosemite-r7-0078', 03:09:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:09:53 INFO - 'use_talos_json': True, 03:09:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:09:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:09:53 INFO - 'work_dir': 'build'} 03:09:53 INFO - ##### 03:09:53 INFO - ##### Running clobber step. 03:09:53 INFO - ##### 03:09:53 INFO - Running pre-action listener: _resource_record_pre_action 03:09:53 INFO - Running main action method: clobber 03:09:53 INFO - rmtree: /builds/slave/test/build 03:09:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:09:55 INFO - Running post-action listener: _resource_record_post_action 03:09:55 INFO - ##### 03:09:55 INFO - ##### Running read-buildbot-config step. 03:09:55 INFO - ##### 03:09:55 INFO - Running pre-action listener: _resource_record_pre_action 03:09:55 INFO - Running main action method: read_buildbot_config 03:09:55 INFO - Using buildbot properties: 03:09:55 INFO - { 03:09:55 INFO - "project": "", 03:09:55 INFO - "product": "firefox", 03:09:55 INFO - "script_repo_revision": "production", 03:09:55 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 03:09:55 INFO - "repository": "", 03:09:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 03:09:55 INFO - "buildid": "20160525183450", 03:09:55 INFO - "pgo_build": "False", 03:09:55 INFO - "basedir": "/builds/slave/test", 03:09:55 INFO - "buildnumber": 301, 03:09:55 INFO - "slavename": "t-yosemite-r7-0078", 03:09:55 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:09:55 INFO - "platform": "yosemite_r7", 03:09:55 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:09:55 INFO - "branch": "mozilla-aurora", 03:09:55 INFO - "repo_path": "releases/mozilla-aurora", 03:09:55 INFO - "revision": "33693d8bdec17f07fc460ca7cc908ade88f97b86", 03:09:55 INFO - "stage_platform": "macosx64", 03:09:55 INFO - "builduid": "63f91898e679451db7d589c242ad058b", 03:09:55 INFO - "slavebuilddir": "test" 03:09:55 INFO - } 03:09:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:09:55 INFO - Running post-action listener: _resource_record_post_action 03:09:55 INFO - ##### 03:09:55 INFO - ##### Running download-and-extract step. 03:09:55 INFO - ##### 03:09:55 INFO - Running pre-action listener: _resource_record_pre_action 03:09:55 INFO - Running main action method: download_and_extract 03:09:55 INFO - mkdir: /builds/slave/test/build/tests 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.test_packages.json 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.test_packages.json 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.test_packages.json 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.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:09:55 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:10:00 INFO - Downloaded 1199 bytes. 03:10:00 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:10:00 INFO - Using the following test package requirements: 03:10:00 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:10:00 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:10:00 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:10:00 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:10:00 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:10:00 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:10:00 INFO - u'jsshell-mac.zip'], 03:10:00 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:10:00 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:10:00 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:10:00 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:10:00 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:10:00 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:10:00 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:10:00 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:10:00 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:10:00 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:10:00 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:10:00 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:10:00 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:00 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:00 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:00 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:00 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:00 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:05 INFO - Downloaded 18425354 bytes. 03:10:05 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:05 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:08 INFO - Return code: 0 03:10:08 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:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:08 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:08 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:08 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:08 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:08 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:11 INFO - Downloaded 18425354 bytes. 03:10:11 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:11 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:15 INFO - Return code: 0 03:10:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:15 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:15 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:15 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:15 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:15 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:16 INFO - Downloaded 10840145 bytes. 03:10:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:10:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:10:16 INFO - Return code: 0 03:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:16 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:16 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:16 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:16 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:16 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:20 INFO - Downloaded 114134973 bytes. 03:10:20 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:20 INFO - mkdir: /builds/slave/test/properties 03:10:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:10:20 INFO - Writing to file /builds/slave/test/properties/build_url 03:10:20 INFO - Contents: 03:10:20 INFO - build_url:https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:10:21 INFO - Running post-action listener: _resource_record_post_action 03:10:21 INFO - Running post-action listener: set_extra_try_arguments 03:10:21 INFO - ##### 03:10:21 INFO - ##### Running populate-webroot step. 03:10:21 INFO - ##### 03:10:21 INFO - Running pre-action listener: _resource_record_pre_action 03:10:21 INFO - Running main action method: populate_webroot 03:10:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:10:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:10:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:10:21 INFO - '--tppagecycles', 03:10:21 INFO - '7'], 03:10:21 INFO - 'tests': ['tsvgm']}}, 03:10:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:10:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:10:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:10:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:10:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:10:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:10:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:21 INFO - 'talos_options': ['--disable-e10s'], 03:10:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:10:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:10:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:21 INFO - 'talos_options': ['--disable-e10s'], 03:10:21 INFO - 'tests': ['damp', 'tps']}, 03:10:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:21 INFO - 'tests': ['damp', 'tps']}, 03:10:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:10:21 INFO - 'tests': ['dromaeo_dom']}, 03:10:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:10:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:10:21 INFO - 'tests': ['a11yr', 03:10:21 INFO - 'ts_paint', 03:10:21 INFO - 'tpaint', 03:10:21 INFO - 'sessionrestore', 03:10:21 INFO - 'sessionrestore_no_auto_restore', 03:10:21 INFO - 'tabpaint']}, 03:10:21 INFO - 'other-e10s': {'tests': ['a11yr', 03:10:21 INFO - 'ts_paint', 03:10:21 INFO - 'tpaint', 03:10:21 INFO - 'sessionrestore', 03:10:21 INFO - 'sessionrestore_no_auto_restore', 03:10:21 INFO - 'tabpaint']}, 03:10:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:10:21 INFO - 'ts_paint', 03:10:21 INFO - 'tpaint', 03:10:21 INFO - 'sessionrestore', 03:10:21 INFO - 'sessionrestore_no_auto_restore', 03:10:21 INFO - 'tabpaint']}, 03:10:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:10:21 INFO - 'ts_paint', 03:10:21 INFO - 'tpaint', 03:10:21 INFO - 'sessionrestore', 03:10:21 INFO - 'sessionrestore_no_auto_restore', 03:10:21 INFO - 'tabpaint']}, 03:10:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:10:21 INFO - 'tests': ['a11yr', 03:10:21 INFO - 'ts_paint', 03:10:21 INFO - 'tpaint', 03:10:21 INFO - 'sessionrestore', 03:10:21 INFO - 'sessionrestore_no_auto_restore', 03:10:21 INFO - 'tabpaint']}, 03:10:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:10:21 INFO - 'tests': ['a11yr', 03:10:21 INFO - 'ts_paint', 03:10:21 INFO - 'tpaint', 03:10:21 INFO - 'sessionrestore', 03:10:21 INFO - 'sessionrestore_no_auto_restore', 03:10:21 INFO - 'tabpaint']}, 03:10:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:10:21 INFO - 'tests': ['tsvgx', 03:10:21 INFO - 'tsvgr_opacity', 03:10:21 INFO - 'tart', 03:10:21 INFO - 'tscrollx', 03:10:21 INFO - 'cart']}, 03:10:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:10:21 INFO - 'tsvgr_opacity', 03:10:21 INFO - 'tart', 03:10:21 INFO - 'tscrollx', 03:10:21 INFO - 'cart']}, 03:10:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:21 INFO - 'talos_options': ['--disable-e10s'], 03:10:21 INFO - 'tests': ['tp5o']}, 03:10:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:21 INFO - 'tests': ['tp5o']}, 03:10:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:21 INFO - 'talos_options': ['--disable-e10s', 03:10:21 INFO - '--xperf_path', 03:10:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:10:21 INFO - 'tests': ['tp5n']}, 03:10:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:21 INFO - 'talos_options': ['--xperf_path', 03:10:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:10:21 INFO - 'tests': ['tp5n']}}, 03:10:21 INFO - 'talos.zip': {'path': '', 03:10:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:10:21 INFO - Running post-action listener: _resource_record_post_action 03:10:21 INFO - ##### 03:10:21 INFO - ##### Running create-virtualenv step. 03:10:21 INFO - ##### 03:10:21 INFO - Running pre-action listener: _resource_record_pre_action 03:10:21 INFO - Running main action method: create_virtualenv 03:10:21 INFO - Creating virtualenv /builds/slave/test/build/venv 03:10:21 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:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:10:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:10:21 INFO - Using real prefix '/tools/python27' 03:10:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:10:22 INFO - Installing distribute.............................................................................................................................................................................................done. 03:10:25 INFO - Installing pip.................done. 03:10:25 INFO - Return code: 0 03:10:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:10:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:25 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 0x104d76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc73b4aac80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c15e00>, '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 0x104a7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c10b20>, '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.xqpFaTIj1k/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.jleJk77zaK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:25 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:25 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:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xqpFaTIj1k/Render', 03:10:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:25 INFO - 'HOME': '/Users/cltbld', 03:10:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:25 INFO - 'LOGNAME': 'cltbld', 03:10:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:25 INFO - 'MOZ_NO_REMOTE': '1', 03:10:25 INFO - 'NO_EM_RESTART': '1', 03:10:25 INFO - 'PAGER': '/bin/cat', 03:10:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:25 INFO - 'PWD': '/builds/slave/test', 03:10:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:25 INFO - 'SHELL': '/bin/bash', 03:10:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jleJk77zaK/Listeners', 03:10:25 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 03:10:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:25 INFO - 'USER': 'cltbld', 03:10:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:25 INFO - 'XPC_FLAGS': '0x0', 03:10:25 INFO - 'XPC_SERVICE_NAME': '0', 03:10:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:25 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:25 INFO - Cleaning up... 03:10:25 INFO - Return code: 0 03:10:25 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:10:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:25 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 0x104d76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc73b4aac80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c15e00>, '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 0x104a7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c10b20>, '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.xqpFaTIj1k/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.jleJk77zaK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:25 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:25 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:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xqpFaTIj1k/Render', 03:10:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:25 INFO - 'HOME': '/Users/cltbld', 03:10:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:25 INFO - 'LOGNAME': 'cltbld', 03:10:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:25 INFO - 'MOZ_NO_REMOTE': '1', 03:10:25 INFO - 'NO_EM_RESTART': '1', 03:10:25 INFO - 'PAGER': '/bin/cat', 03:10:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:25 INFO - 'PWD': '/builds/slave/test', 03:10:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:25 INFO - 'SHELL': '/bin/bash', 03:10:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jleJk77zaK/Listeners', 03:10:25 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 03:10:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:25 INFO - 'USER': 'cltbld', 03:10:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:25 INFO - 'XPC_FLAGS': '0x0', 03:10:25 INFO - 'XPC_SERVICE_NAME': '0', 03:10:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:25 INFO - Downloading/unpacking psutil>=0.7.1 03:10:25 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:25 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:25 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:25 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:25 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:25 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:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:10:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:10:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:10:28 INFO - Installing collected packages: psutil 03:10:28 INFO - Running setup.py install for psutil 03:10:28 INFO - building 'psutil._psutil_osx' extension 03:10:28 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:29 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:29 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:29 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:29 INFO - building 'psutil._psutil_posix' extension 03:10:29 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:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:10:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:10:29 INFO - ^ 03:10:29 INFO - 1 warning generated. 03:10:29 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:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:10:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:10:30 INFO - Successfully installed psutil 03:10:30 INFO - Cleaning up... 03:10:30 INFO - Return code: 0 03:10:30 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:10:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:30 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 0x104d76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc73b4aac80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c15e00>, '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 0x104a7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c10b20>, '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.xqpFaTIj1k/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.jleJk77zaK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:30 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:30 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:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xqpFaTIj1k/Render', 03:10:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:30 INFO - 'HOME': '/Users/cltbld', 03:10:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:30 INFO - 'LOGNAME': 'cltbld', 03:10:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:30 INFO - 'MOZ_NO_REMOTE': '1', 03:10:30 INFO - 'NO_EM_RESTART': '1', 03:10:30 INFO - 'PAGER': '/bin/cat', 03:10:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:30 INFO - 'PWD': '/builds/slave/test', 03:10:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:30 INFO - 'SHELL': '/bin/bash', 03:10:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jleJk77zaK/Listeners', 03:10:30 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 03:10:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:30 INFO - 'USER': 'cltbld', 03:10:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:30 INFO - 'XPC_FLAGS': '0x0', 03:10:30 INFO - 'XPC_SERVICE_NAME': '0', 03:10:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:30 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:32 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:10:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:10:32 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:32 INFO - Installing collected packages: mozsystemmonitor 03:10:32 INFO - Running setup.py install for mozsystemmonitor 03:10:33 INFO - Successfully installed mozsystemmonitor 03:10:33 INFO - Cleaning up... 03:10:33 INFO - Return code: 0 03:10:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:10:33 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:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:33 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:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:33 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 0x104d76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc73b4aac80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c15e00>, '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 0x104a7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c10b20>, '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.xqpFaTIj1k/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.jleJk77zaK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:33 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:33 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:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xqpFaTIj1k/Render', 03:10:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:33 INFO - 'HOME': '/Users/cltbld', 03:10:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:33 INFO - 'LOGNAME': 'cltbld', 03:10:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:33 INFO - 'MOZ_NO_REMOTE': '1', 03:10:33 INFO - 'NO_EM_RESTART': '1', 03:10:33 INFO - 'PAGER': '/bin/cat', 03:10:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:33 INFO - 'PWD': '/builds/slave/test', 03:10:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:33 INFO - 'SHELL': '/bin/bash', 03:10:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jleJk77zaK/Listeners', 03:10:33 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 03:10:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:33 INFO - 'USER': 'cltbld', 03:10:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:33 INFO - 'XPC_FLAGS': '0x0', 03:10:33 INFO - 'XPC_SERVICE_NAME': '0', 03:10:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:33 INFO - Downloading/unpacking blobuploader==1.2.4 03:10:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:35 INFO - Downloading blobuploader-1.2.4.tar.gz 03:10:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:10:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:10:35 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:35 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:35 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:35 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:35 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:35 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:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:10:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:37 INFO - Downloading docopt-0.6.1.tar.gz 03:10:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:10:37 INFO - Installing collected packages: blobuploader, requests, docopt 03:10:37 INFO - Running setup.py install for blobuploader 03:10:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:10:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:10:37 INFO - Running setup.py install for requests 03:10:37 INFO - Running setup.py install for docopt 03:10:37 INFO - Successfully installed blobuploader requests docopt 03:10:37 INFO - Cleaning up... 03:10:38 INFO - Return code: 0 03:10:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:38 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 0x104d76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc73b4aac80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c15e00>, '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 0x104a7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c10b20>, '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.xqpFaTIj1k/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.jleJk77zaK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:38 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:38 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:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xqpFaTIj1k/Render', 03:10:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:38 INFO - 'HOME': '/Users/cltbld', 03:10:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:38 INFO - 'LOGNAME': 'cltbld', 03:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:38 INFO - 'MOZ_NO_REMOTE': '1', 03:10:38 INFO - 'NO_EM_RESTART': '1', 03:10:38 INFO - 'PAGER': '/bin/cat', 03:10:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:38 INFO - 'PWD': '/builds/slave/test', 03:10:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:38 INFO - 'SHELL': '/bin/bash', 03:10:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jleJk77zaK/Listeners', 03:10:38 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 03:10:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:38 INFO - 'USER': 'cltbld', 03:10:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:38 INFO - 'XPC_FLAGS': '0x0', 03:10:38 INFO - 'XPC_SERVICE_NAME': '0', 03:10:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:10:38 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-vGPH9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:10:38 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-2XXiw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:10:38 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-if0HUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:10:38 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-7YsAaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:10:38 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-bYCgoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:10:38 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-skEvro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:10:38 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-MqXKU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:10:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-ooEGXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:10:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-MRtzjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:10:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-ehye1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:10:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-gjdXcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:10:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-ElO_m1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:10:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-WV8HOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:10:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-IyOJLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:10:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-Z_WDnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:10:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-LPy1uf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:10:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:10:39 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-l8TaHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:10:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:10:40 INFO - Running setup.py install for manifestparser 03:10:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:10:40 INFO - Running setup.py install for mozcrash 03:10:40 INFO - Running setup.py install for mozdebug 03:10:40 INFO - Running setup.py install for mozdevice 03:10:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:10:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:10:40 INFO - Running setup.py install for mozfile 03:10:40 INFO - Running setup.py install for mozhttpd 03:10:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:10:40 INFO - Running setup.py install for mozinfo 03:10:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:10:41 INFO - Running setup.py install for mozInstall 03:10:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:10:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:10:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:10:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:10:41 INFO - Running setup.py install for mozleak 03:10:41 INFO - Running setup.py install for mozlog 03:10:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:10:41 INFO - Running setup.py install for moznetwork 03:10:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:10:41 INFO - Running setup.py install for mozprocess 03:10:41 INFO - Running setup.py install for mozprofile 03:10:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:10:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:10:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:10:42 INFO - Running setup.py install for mozrunner 03:10:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:10:42 INFO - Running setup.py install for mozscreenshot 03:10:42 INFO - Running setup.py install for moztest 03:10:42 INFO - Running setup.py install for mozversion 03:10:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:10:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:10:42 INFO - Cleaning up... 03:10:42 INFO - Return code: 0 03:10:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:10:42 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:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:42 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:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:42 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 0x104d76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc73b4aac80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c15e00>, '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 0x104a7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c10b20>, '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.xqpFaTIj1k/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.jleJk77zaK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:42 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:42 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:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xqpFaTIj1k/Render', 03:10:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:42 INFO - 'HOME': '/Users/cltbld', 03:10:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:42 INFO - 'LOGNAME': 'cltbld', 03:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:42 INFO - 'MOZ_NO_REMOTE': '1', 03:10:42 INFO - 'NO_EM_RESTART': '1', 03:10:42 INFO - 'PAGER': '/bin/cat', 03:10:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:42 INFO - 'PWD': '/builds/slave/test', 03:10:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:42 INFO - 'SHELL': '/bin/bash', 03:10:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jleJk77zaK/Listeners', 03:10:42 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 03:10:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:42 INFO - 'USER': 'cltbld', 03:10:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:42 INFO - 'XPC_FLAGS': '0x0', 03:10:42 INFO - 'XPC_SERVICE_NAME': '0', 03:10:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:10:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-JqD0lN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:10:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:10:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-49EMyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:10:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:10:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-rJEd02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:10:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:10:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-AkdOAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:10:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:10:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-oO5jGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:10:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:10:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-IhHo3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:10:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:10:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-ourl0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:10:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:10:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-q3uido-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:10:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:10:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-fDi2jD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:10:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:10:43 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-BxRVwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:10:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:10:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-5XmZOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:10:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:10:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-qTsZif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:10:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:10:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-ULyVHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:10:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:10:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-UXtPhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:10:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:10:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-jXDSeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:10:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:10:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-mhQ8VL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:10:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:10:44 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-2dvuQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:10:44 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:44 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:44 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:44 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:44 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:44 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:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 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:47 INFO - Downloading blessings-1.6.tar.gz 03:10:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:10:47 INFO - Installing collected packages: blessings 03:10:47 INFO - Running setup.py install for blessings 03:10:47 INFO - Successfully installed blessings 03:10:47 INFO - Cleaning up... 03:10:47 INFO - Return code: 0 03:10:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:10:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:10:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:10:47 INFO - Reading from file tmpfile_stdout 03:10:47 INFO - Current package versions: 03:10:47 INFO - blessings == 1.6 03:10:47 INFO - blobuploader == 1.2.4 03:10:47 INFO - docopt == 0.6.1 03:10:47 INFO - manifestparser == 1.1 03:10:47 INFO - mozInstall == 1.12 03:10:47 INFO - mozcrash == 0.17 03:10:47 INFO - mozdebug == 0.1 03:10:47 INFO - mozdevice == 0.48 03:10:47 INFO - mozfile == 1.2 03:10:47 INFO - mozhttpd == 0.7 03:10:47 INFO - mozinfo == 0.9 03:10:47 INFO - mozleak == 0.1 03:10:47 INFO - mozlog == 3.1 03:10:47 INFO - moznetwork == 0.27 03:10:47 INFO - mozprocess == 0.22 03:10:47 INFO - mozprofile == 0.28 03:10:47 INFO - mozrunner == 6.11 03:10:47 INFO - mozscreenshot == 0.1 03:10:47 INFO - mozsystemmonitor == 0.0 03:10:47 INFO - moztest == 0.7 03:10:47 INFO - mozversion == 1.4 03:10:47 INFO - psutil == 3.1.1 03:10:47 INFO - requests == 1.2.3 03:10:47 INFO - wsgiref == 0.1.2 03:10:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:10:47 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:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:47 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:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:47 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 0x104d76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc73b4aac80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c15e00>, '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 0x104a7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c10b20>, '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.xqpFaTIj1k/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.jleJk77zaK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:47 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:47 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:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xqpFaTIj1k/Render', 03:10:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:47 INFO - 'HOME': '/Users/cltbld', 03:10:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:47 INFO - 'LOGNAME': 'cltbld', 03:10:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:47 INFO - 'MOZ_NO_REMOTE': '1', 03:10:47 INFO - 'NO_EM_RESTART': '1', 03:10:47 INFO - 'PAGER': '/bin/cat', 03:10:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:47 INFO - 'PWD': '/builds/slave/test', 03:10:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:47 INFO - 'SHELL': '/bin/bash', 03:10:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jleJk77zaK/Listeners', 03:10:47 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 03:10:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:47 INFO - 'USER': 'cltbld', 03:10:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:47 INFO - 'XPC_FLAGS': '0x0', 03:10:47 INFO - 'XPC_SERVICE_NAME': '0', 03:10:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:48 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:48 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:48 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:48 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:48 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:48 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:48 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:48 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:48 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:48 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:48 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:48 INFO - Cleaning up... 03:10:48 INFO - Return code: 0 03:10:48 INFO - Installing jsonschema 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', '--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 0x104d76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc73b4aac80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c15e00>, '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 0x104a7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c10b20>, '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.xqpFaTIj1k/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.jleJk77zaK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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', '--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:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:10:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xqpFaTIj1k/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.jleJk77zaK/Listeners', 03:10:48 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:48 INFO - Downloading/unpacking jsonschema 03:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:10:50 INFO - Downloading/unpacking functools32 (from jsonschema) 03:10:50 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:50 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:50 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:50 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:50 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:50 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:51 INFO - Downloading functools32-3.2.3-2.tar.gz 03:10:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:10:51 INFO - warning: no files found matching '*.txt' 03:10:51 INFO - no previously-included directories found matching 'build' 03:10:51 INFO - no previously-included directories found matching 'dist' 03:10:51 INFO - no previously-included directories found matching '.git*' 03:10:51 INFO - Installing collected packages: jsonschema, functools32 03:10:51 INFO - Running setup.py install for functools32 03:10:51 INFO - warning: no files found matching '*.txt' 03:10:51 INFO - no previously-included directories found matching 'build' 03:10:51 INFO - no previously-included directories found matching 'dist' 03:10:51 INFO - no previously-included directories found matching '.git*' 03:10:51 INFO - Successfully installed jsonschema functools32 03:10:51 INFO - Cleaning up... 03:10:51 INFO - Return code: 0 03:10:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:10:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104d76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc73b4aac80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c15e00>, '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 0x104a7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c10b20>, '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.xqpFaTIj1k/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.jleJk77zaK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:10:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:10:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xqpFaTIj1k/Render', 03:10:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:51 INFO - 'HOME': '/Users/cltbld', 03:10:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:51 INFO - 'LOGNAME': 'cltbld', 03:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:51 INFO - 'MOZ_NO_REMOTE': '1', 03:10:51 INFO - 'NO_EM_RESTART': '1', 03:10:51 INFO - 'PAGER': '/bin/cat', 03:10:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:51 INFO - 'PWD': '/builds/slave/test', 03:10:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:51 INFO - 'SHELL': '/bin/bash', 03:10:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jleJk77zaK/Listeners', 03:10:51 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 03:10:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:51 INFO - 'USER': 'cltbld', 03:10:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:51 INFO - 'XPC_FLAGS': '0x0', 03:10:51 INFO - 'XPC_SERVICE_NAME': '0', 03:10:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:51 INFO - Downloading/unpacking flake8 03:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:10:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:54 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:10:54 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 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:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:10:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:10:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:10:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:10:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:10:55 INFO - no previously-included directories found matching 'docs/_build' 03:10:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:10:55 INFO - Running setup.py install for pep8 03:10:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:10:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:10:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:10:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:10:55 INFO - no previously-included directories found matching 'docs/_build' 03:10:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:10:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:10:55 INFO - Cleaning up... 03:10:56 INFO - Return code: 0 03:10:56 INFO - Running post-action listener: _resource_record_post_action 03:10:56 INFO - Running post-action listener: _start_resource_monitoring 03:10:56 INFO - Starting resource monitoring. 03:10:56 INFO - ##### 03:10:56 INFO - ##### Running install step. 03:10:56 INFO - ##### 03:10:56 INFO - Running pre-action listener: _resource_record_pre_action 03:10:56 INFO - Running main action method: install 03:10:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:10:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:10:56 INFO - Reading from file tmpfile_stdout 03:10:56 INFO - Detecting whether we're running mozinstall >=1.0... 03:10:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:10:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:10:56 INFO - Reading from file tmpfile_stdout 03:10:56 INFO - Output received: 03:10:56 INFO - Usage: mozinstall [options] installer 03:10:56 INFO - Options: 03:10:56 INFO - -h, --help show this help message and exit 03:10:56 INFO - -d DEST, --destination=DEST 03:10:56 INFO - Directory to install application into. [default: 03:10:56 INFO - "/builds/slave/test"] 03:10:56 INFO - --app=APP Application being installed. [default: firefox] 03:10:56 INFO - mkdir: /builds/slave/test/build/application 03:10:56 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:10:56 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:28 INFO - Reading from file tmpfile_stdout 03:11:28 INFO - Output received: 03:11:28 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:11:28 INFO - Running post-action listener: _resource_record_post_action 03:11:28 INFO - ##### 03:11:28 INFO - ##### Running run-tests step. 03:11:28 INFO - ##### 03:11:28 INFO - Running pre-action listener: _resource_record_pre_action 03:11:28 INFO - Running main action method: run_tests 03:11:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:11:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:11:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:11:28 INFO - 2016-05-28 03:11:28.076 screenresolution[1650:8406] starting screenresolution argv=screenresolution get 03:11:28 INFO - 2016-05-28 03:11:28.079 screenresolution[1650:8406] Display 0: 1600x1200x32@60 03:11:28 INFO - 2016-05-28 03:11:28.085 screenresolution[1651:8408] starting screenresolution argv=screenresolution list 03:11:28 INFO - Available Modes on Display 0 03:11:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:11:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:11:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:11:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:11:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:11:28 INFO - Intel Iris: 03:11:28 INFO - Chipset Model: Intel Iris 03:11:28 INFO - Type: GPU 03:11:28 INFO - Bus: Built-In 03:11:28 INFO - VRAM (Dynamic, Max): 1536 MB 03:11:28 INFO - Vendor: Intel (0x8086) 03:11:28 INFO - Device ID: 0x0a2e 03:11:28 INFO - Revision ID: 0x0009 03:11:28 INFO - Displays: 03:11:28 INFO - Display: 03:11:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:11:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:11:28 INFO - Display Serial Number: 1600x1200 60 03:11:28 INFO - Main Display: Yes 03:11:28 INFO - Mirror: Off 03:11:28 INFO - Online: Yes 03:11:28 INFO - Rotation: Supported 03:11:28 INFO - Return code: 0 03:11:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:11:28 INFO - Python 2.7.3 03:11:28 INFO - Return code: 0 03:11:28 INFO - grabbing minidump binary from tooltool 03:11:28 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:28 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 0x104c15e00>, '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 0x104a7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c10b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:11:28 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:28 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:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:11:28 INFO - Return code: 0 03:11:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:11:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:11:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:11:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:11:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:11:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:11:29 INFO - Return code: 0 03:11:29 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-e10s', '--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-0078', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:11:29 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-e10s --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-0078 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:11:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xqpFaTIj1k/Render', 03:11:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:29 INFO - 'HOME': '/Users/cltbld', 03:11:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:29 INFO - 'LOGNAME': 'cltbld', 03:11:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:11:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:29 INFO - 'MOZ_NO_REMOTE': '1', 03:11:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:11:29 INFO - 'NO_EM_RESTART': '1', 03:11:29 INFO - 'PAGER': '/bin/cat', 03:11:29 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:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:29 INFO - 'PWD': '/builds/slave/test', 03:11:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:11:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:29 INFO - 'SHELL': '/bin/bash', 03:11:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jleJk77zaK/Listeners', 03:11:29 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 03:11:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:29 INFO - 'USER': 'cltbld', 03:11:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:29 INFO - 'XPC_FLAGS': '0x0', 03:11:29 INFO - 'XPC_SERVICE_NAME': '0', 03:11:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--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-0078', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:11:29 INFO - mozversion application_buildid: 20160525183450 03:11:29 INFO - mozversion application_changeset: 33693d8bdec17f07fc460ca7cc908ade88f97b86 03:11:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:11:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:11:29 INFO - mozversion application_name: Firefox 03:11:29 INFO - mozversion application_remotingname: firefox-dev 03:11:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:11:29 INFO - mozversion application_vendor: Mozilla 03:11:29 INFO - mozversion application_version: 48.0a2 03:11:29 INFO - mozversion platform_buildid: 20160525183450 03:11:29 INFO - mozversion platform_changeset: 33693d8bdec17f07fc460ca7cc908ade88f97b86 03:11:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:11:29 INFO - mozversion platform_version: 48.0a2 03:11:29 INFO - using testdate: 1464430289 03:11:29 INFO - actual date: 1464430289 03:11:29 INFO - starting webserver on 'localhost:49283' 03:11:29 INFO - SUITE-START | Running 2 tests 03:11:29 INFO - TEST-START | dromaeo_css 03:11:29 INFO - operating with platform_type : mac_ 03:11:29 INFO - Initialising browser for dromaeo_css test... 03:11:29 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpNF0HFW/profile http://localhost:49283/getInfo.html) 03:11:32 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 03:11:32 INFO - PROCESS | 1669 | colorDepth:24 03:11:32 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 03:11:32 INFO - PROCESS | 1669 | __metrics 03:11:32 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 03:11:33 INFO - TEST-INFO | 1669: exit 0 03:11:33 INFO - Browser initialized. 03:11:33 INFO - Running cycle 1/1 for dromaeo_css test... 03:11:33 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpNF0HFW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:11:45 INFO - PROCESS | 1673 | RSS: Main: 220803072 03:11:45 INFO - PROCESS | 1673 | 03:12:35 INFO - PROCESS | 1673 | 1464430355211 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:46 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49283/tests/dromaeo/cssquery-ext.html) 03:14:47 INFO - PROCESS | 1673 | RSS: Main: 231239680 03:14:47 INFO - PROCESS | 1673 | 03:17:49 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-ext.html (next: http://localhost:49283/tests/dromaeo/cssquery-jquery.html) 03:17:50 INFO - PROCESS | 1673 | RSS: Main: 244006912 03:17:50 INFO - PROCESS | 1673 | 03:20:52 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49283/tests/dromaeo/cssquery-mootools.html) 03:20:52 INFO - PROCESS | 1673 | RSS: Main: 245993472 03:20:52 INFO - PROCESS | 1673 | 03:23:54 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49283/tests/dromaeo/cssquery-prototype.html) 03:23:55 INFO - PROCESS | 1673 | RSS: Main: 247783424 03:23:55 INFO - PROCESS | 1673 | 03:26:57 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49283/tests/dromaeo/cssquery-yui.html) 03:26:58 INFO - PROCESS | 1673 | RSS: Main: 250064896 03:26:58 INFO - PROCESS | 1673 | 03:30:00 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-yui.html (next: http://localhost:49283/tests/dromaeo/cssquery-dojo.html) 03:30:00 INFO - PROCESS | 1673 | RSS: Main: 251772928 03:30:00 INFO - PROCESS | 1673 | 03:30:00 INFO - PROCESS | 1673 | __start_tp_report 03:30:00 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 03:30:00 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 03:30:00 INFO - PROCESS | 1673 | |i|pagename|runs| 03:30:00 INFO - PROCESS | 1673 | |0;dojo.html;3427.897163085107;3450.2064525159212;3462.9826850865743;3486.389881770691;3495.685388315053;5665.971670141654;5697.4302569743;5698.544116470683;5720.771169153234;5773.826785196451;5725.685087320196;5748.770049198038;5755.309362876461;5755.740991655375;5783.971080144604;5180.300659410978;5225.137852254374;5271.604629652772;5297.947020529784;5298.046351656701;4365.607095361417;5169.870753231162;5325.988062268176;5369.62412631116;5373.059714549958;5212.687238765668;5288;5302.973485132569;5319.494648008433;5331.626786124973;5287.180487024537;5313.149896016638;5316.069687804618;5322.467753224647;5354.678719276856;4511.669057628008;5033.446320904697;5097.3118628984985;5227.555657769056;5249.973750131263;5465.9726701366235;5512.145617429305;5515;5587.161925711135;5605.691686957216;6161.7227224775;6205.565610407273;6243.906341404882;6246.87506249877;6252.155958945529;5303.522682958553;6216.222972128484;6220.813375598739;6223.87552248957;6264.906026409608;5776.451237132465;5786.508146807484;5790.392008839095;5798.391168927286;5810.3899090595305;6282.811515654537;6290.559660823698;6291.96854015727;6292.811215663446;6300.495960323163;4734.786934587952;5449.618526703172;5685.1756495307945;5754.798582049608;6101.84745381369;5254.211868219798;6250.4374606285655;6268.4671802896355;6283.622982621057;6293.30773614902;5090.287359769635;5142.357205349332;6062.696865156725;6113.541484388688;6247.094171345129;6164.137020817089;6206.968965155145;6243.344448832898;6270.184875966186;6277.309495955441;4913;4976.62675299354;4978.278149668277;4981.850544483599;4984.551390374883;4263;4400.73595584266;4403.361512580658;4476.194285028726;4481.260592002348;5603.075492543685;5696.174054762037;5730.08318669011;5833.066709326487;5861.062230043172;6208.441240288395;6209.59637623553;6265.154204182468;6268.49852011838;6272.153259310026;5580.748866301026;5662.235598194274;5751.367349591542;5816.272965879265;5870.559708021916;4791.616670666338;5389.380221274603;5427.3215848019;5432.728363581805;5451.236826844245;6336.081268216175;6346.9682651586445;6373.458256048102;6373.840653983688;6375.266844312778;6140.964409184426;6207.037909124093;6276.905846412399;6281.183446151971;6302.376133723036;6349.301576826453;6349.9682501587195;6350.333215012357;6364.172657554488;6366.77716279928;6176.258848937977;6195.81412557624;6197.566170368031;6199.938000620116;6209.534284928739;6171.660558669137;6225.159603453657;6265.718042687999;6279.843003924756;6284.497240220863;6076.969615151896;6081.361457046946;6112.72492737819;6125.296497837281;6131.316864617588;5933.940660593339;6114.663693496723;6121.84695382619;6149.784757533644;6163.291221509584;4893.584045356185;5080.415752188545;5448.591355648421;5790.623609465371;6047.304559975483;6063.848403789941;6072.423119803611;6072.757089716539;6079.392060793885;6085.148079268817;5794.536437084938;5967.313758917607;5979.820605381846;5995.730192141451;5998.160257563857 03:30:00 INFO - PROCESS | 1673 | |1;ext.html;523.72766161596;525.3459442993474;555.8999380111578;581.5754499215573;606.2391698418484;37656;39226.019349516275;39259.80370098146;39264;39290.41064384037;39807.00482487939;39877.80061099691;40290.59709402905;40432.59567404325;40509.18981620373;9351.859722104175;9540.856887146698;9605.087516685939;9630.90369096307;9633.27750418718;9436.433813971149;9586.856197157049;9641.662541811038;9660.420374777501;9733.464659443727;9637.132658060773;9663.95168024159;9675.564599593035;9700.902990970071;9744.415335079882;9261.814763704755;9618.663346782863;9667.984861589503;9674.226061915066;9681.41911485306;9551.0926461986;9655.951720241424;9673.90326096737;9688.128068473801;9698.903010969942;39624.20751584981;39850.60149398498;39935.60064399348;39974.20051598981;40116;2181.7490988536365;2640.735926407363;3076.615423072116;3082.1370016395445;3254.495553189259;29232.853835730686;29364;29522.852385738148;30387.240318991982;31488.685113148804;2477.2320580620067;2765.5713364428343;2779.138467075213;2788.8047836651444;2806.915792526207;328437;388883;390378;396960;397249.01375493227;37714.81142594324;38515.42226866599;67246.99129513062;68375.3162468369;68456.97314540287;36891;37052.888413347646;37086.887393378245;37129.88610341694;37369.62630373716;14206.21865797391;14245.430182792572;14350.210738409487;14355.641108972359;14397.352119154662;11487.368194749202;11639.486866707273;13377.73244535104;13401.86598134006;13518.121322114031;6865.519413640997;8227;8512.659493620185;8557.44376615518;8597;3304.454764963754;3328.1513214130728;3505.5793304803587;3510.227749895019;3512.7892326459983;935.4200395754675;966.9315406475743;1073.2058276875057;1101.6805126513382;1129.949152288149;27626;28574.714252857622;30466.238344041576;30609.081727548208;30623.846880765454;1048.5124417146064;1077.5743581285349;1090.4275255490932;1120.6638008597552;1160.6344001639457;12270.073356953975;15010.24948752558;15072.773908391382;15144.242787860563;15378.00042997224;286055;287176;288887;289335.55332223204;290201;55255.17117243164;61482;62494;63124;63435;1495.1178804505394;1502.128765316136;1509.3660662521768;1510.7431736604804;1511.8261399938929;28679.139625811258;29220.7077929218;29591.85204074052;29996.400071998003;30201.395972080874;53096;53552;53713.731431342596;53767;53808;544735;558891;561310;563466;563561;28173.591320434305;28283.2929176764;28504.57243141313;28563.714362856106;28647.5702864453;46152.30771538363;46293.30560041535;47292.29061564148;47857.76071119622;48097;38617.21999452091;38787.80606096952;38879.63921262742;39340;39802.40296395614;37942.43086353762;38054;38430.80784596059;38540.03649908775;39119.41320880132;32851.67148328486;37607;38636.03409914775;39134.02164945899;39201.41197881977;30623.846880765454;31290.687093128778;32454.188645283113;34427.82786086053;34623;21406;24862.37844053913;25189;25505;25556.361090972874 03:30:00 INFO - PROCESS | 1673 | |2;jquery.html;392.8958825911134;397.24523405529493;398.48794299325374;400.6474302613701;401.6184624606624;19748.70376944347;19911.800881991177;20352.18591256348;20412.081456334465;21265.36203913885;20570.794292057075;20687.862167580777;20933.371998840023;21065.26271580495;21094.261700840434;5733.397993210715;5777.104548794943;6076.756929722806;6079.756809727605;6083.239595050619;5866.061430171173;5873.383294754053;5981.342052374235;6017.939820601781;6050.818475445722;6117.44942955134;6128.448439640431;6163.167972323746;6164.07538869171;6168.629882207059;5676.545876329884;6111.358307377728;6115.755369785204;6128.846778830521;6136.539759518009;4983.651144419856;5764.423557644244;5784.942150578482;5788.42115788422;5798.971005144989;22731.659025114637;25079;25499.980000799947;25647;25858;2032.3293313206607;2119.0993827623197;2168.566286742642;2245.2141750387395;2704.7971402144917;9834.114929656365;12836.422360993778;12863.356832158448;14242.572722818331;14750.704985900222;1746.8645380502649;1752.220261983418;1862.9068546572616;1902.258119333461;1930.806919308072;106099.46950265276;106877.39683904748;110723;114535;119437;9764.609415623438;11573.974554452938;11839.88160118405;11845.822312665317;12049.27704337725;15233.542993709985;15234.93355465129;15261.008034477723;15283.770743438858;15311.46409875649;8764.298856091498;8861.689840855539;8997.505137217386;9051.230645395084;9099.454032758056;7531.661075251627;9093.954530227438;9223.76940576491;9275.62897484093;9426.481543515036;4726.834560790356;4749.667523273338;4756.334113224151;4795;4795.352627395327;1998.7201791749167;2019.8485113616243;2030.3908827351736;2051.7332746743127;2091.351680978901;828.1180542721982;923.3259720404143;934.5747684803398;969.2585172343163;1020.4030642074321;19490.707639385422;20582.176712931614;20605.072771725307;20668.689969650466;20775.37673869786;1001.5643195210107;1026.825439675257;1027.2295778166376;1030.994845025785;1033.478093562761;10650.680479585624;11037.227394082498;11077.279976801483;11094.00153986145;11114.19728592967;501139;552599;599689;622003;624139;104178.48646819372;114944.85055149341;121237;129069;137965;873.3362644390309;996.0537489385113;1040.869891263592;1041.9843702344472;1053.4206186597187;17669.028203448936;17808.3767068152;17873.73189402186;17884.91057544704;18247.54381140429;40235.79292621226;43361;44742.55257447384;46267.6119716409;55926;71731;75428.24571754212;77496.22503775115;81983.59008204921;84525.57737211275;7891.684332626631;8123.268905798506;8160.918390816016;8246.546439945621;8328.625211865601;8949.284057275532;11712.70718232051;12365.876341236473;12738.68153296175;12750.298733569372;10584.629537966135;10757.515911783832;10940.288881222696;11045.502952367324;11123.27698699582;8748.78128046804;10650.57397704083;10797.244192906735;10815.35107893529;11040.558377664804;10922.399268040017;11095.389753563632;11390.715232119263;11424.143189260672;11452.828207576726;8139.674413023413;8247.381446391424;8272.627731752205;8466.788330291793;11227.55089796399;9640.662576809778;11260.817614150395;11722;11738.94130529342;12011.879881201427 03:30:00 INFO - PROCESS | 1673 | |3;mootools.html;1418.5602463236398;1611.7985251843522;1800.279888044782;1891.1678861301018;1949.298252629054;7896.131425543185;7912;8731.039585645574;8951.776205594855;8960.327975401846;7389.5935723535185;8635.611397487113;8773.341999350043;8786.12138786121;8802.251808596277;2371.276760588016;2392.3301475586863;2399.4361325088676;2403.90622266869;2407.8194135439826;2385.8807059646947;2411.1922505960524;2473.3445636906235;2480.466699659573;2482.6772519572432;2445.987770061156;2447.596146635803;2465.1988103866224;2471.122751423246;2474.9752502474926;2405.206281926977;2417.9274621761288;2421.418859473731;2432.62294344376;2447.229122826304;2452.448199155194;2470.234227389497;2472.9629055564183;2473.3692908308267;2477.9504409911883;8953.328500362433;8977.832881725333;8994.41536300145;8999.955000224956;9093.363464557484;5289.100852855024;5400.973814975181;5466.34403871534;5469.316335458067;5476.890462190774;3405.3529829332347;3436.398630239698;3463.809490478023;3470.548828652284;3524.6475352464804;885.7829831691276;1044.2272718188485;1059.9417032063234;1492.1196494068552;1532.8620424161768;11377.431128443628;11445.141614378956;11475.713107172387;11481.655550333504;11513.884861151366;4507.797149128297;4585.2663573828695;4629.518554062668;4632.837850675211;4634.9339651880255;8242.258196762321;8633.007204171601;8761.685747137853;9119.042500537347;9135.54322283883;4773.498782627844;5255.816046438356;5281.0758117329315;5336.092864213093;5370.597205209625;4406.647468202536;4804.855854324376;4858.562729354375;4895.6083513318845;4917.213245880714;3868.4777555030273;3868.941965870514;3944.723869329177;3946.072672921861;3981.1838573092446;1114.4706264524448;1132.9546818127346;1211.0372254057868;1215.5745489078752;1224.2715584227371;841.6801615386114;852.9744107676779;864.5893200729729;935.8689783430189;941.9529023548795;5603.523700485504;5785.103308987113;5809.535237181015;5861.912071318934;5905.616134951214;848.4442690038035;983.1643103362085;988;992.4888682328607;1035.223582313265;3830.8084595770656;3905.6875449964505;3910.8631197907935;3923.9215215695526;3978.801059947049;10676.572937082585;10928.617498387519;10941.83587246192;11068.28056176346;11226.326420414802;10897.400642964554;10967.890321096687;11008.944955275172;11019;11053.226274160892;8081.6363263653275;8468.280196183394;8511.531865747444;8555.486670799772;8611.913880861237;4726.385569875894;5498.45015498447;5587.8882422352135;5600.187972744011;5780.190773291659;9528.475933823705;9676.612935482783;9765.80468390614;9774.071463210983;9783.315167938175;9434.05659434051;9641.228701703987;9822;9899.604015839286;9955.20358371343;6794.320567943166;7091.964540177472;7312;7374.410047196318;7536.7362142324755;8048.356131509582;8057.234562716767;8067.515949043076;8070.83858322842;8101.189881011852;7019.508634395747;7149.14210294768;7153.570785752871;7242.96378518104;7305.488615797056;7031.507794454339;7059.858802824017;7076.646167691698;7166.928330716834;7199.028131202221;6730.956701711242;6902.689378977858;6903.689333979883;6909.205441374105;6924.480663950123;4712.3873896394625;4756.143894098957;4761.9285710713675;4870.196417591058;4885.291632713308;4816.15717249487;4827.986122914227;4852.126617208936;4891.070696567641;4909.656324057424 03:30:00 INFO - PROCESS | 1673 | |4;prototype.html;301.31000009977146;301.6229712858926;328.86187801123526;331.09280571234814;333.48310119315073;5273.1562949927975;6535.771248006321;6576.704048317825;6652.334766523351;6712.261651218369;6610.735570577183;6705.631190284533;6719.899201511969;6732;6736.7978960631135;4976.154053810861;5009.098362294785;5019.146745053332;5020.7489625518765;5034.647574669774;5018.849434516952;5026.170681837492;5028.346314979048;5032.245163225509;5035.446100928895;4958.206686930092;4964.304997300363;4972.726500042496;4977.029479251547;4993.475685053071;4652.325412815157;4807.663463557552;4958.603311735071;4966.329545511346;4972.801087956477;4083.4079058536645;4094.242565125461;4100.2414553307435;4103.076807718269;4114.855980040714;5013.6490445668815;6343;6345.270293916166;6354.650494222816;6356.586821856565;5658;5660.858478538028;5665.971670141664;5675.602707810455;5708.1723150143;7059.188193357778;7063.434925206023;7074.929250707479;7075.504714669975;7139.893316535945;4421.4694236691485;4577.725336479789;4579.404677391918;4589.1968905441745;4590.724556526619;5941.55438342126;6011.639301641915;7440.516366436164;7522.059742532184;7547.62261886909;4009.799510024487;4646.442426908792;4651.86044418668;4672.901868061003;4677.462091859412;4707.976460117746;4719.7404142772475;4733.195356789354;4740.3837501124635;4742.786574604151;5627.52166065889;5629.268195134607;5637.351704553947;5642.984262832648;5667.404922483162;6009.879802403927;6818.4886133540185;6933.168019837652;7142.392896603847;7148.642567871586;1642.3184378482795;1643.2769581384152;1662.742274947385;1663.717168081429;1670.9415170468976;955.6941778630771;1042;1046.7801761629992;1061.0662616897082;1066.088494337346;6579.80260592183;6650.3349665034075;6660.400563949268;6716.126903502513;6751.966240168767;6113.541484388688;7484;7496.775096746997;7499.175090740015;7524.247575242432;5362.731863406892;6688.498362622823;6688.899666505006;6731.663416829139;6740.4270636995425;3925.685945124383;3966.1076257841755;3968.7618742874943;3981.880543583697;3982.1438390745548;7555.282248186413;7814.921850781533;7850;7850.921490785133;7880.133185349607;7686.500377475446;7704.8844267336035;7706.344960678293;7708.190639982833;7738.574378409242;7879.369650427952;7882.211778822187;7894.368450523944;7907.051153861574;7949.7217597383815;6005.939940600713;7173.282671732785;7380.77857664271;7443.888341674771;7451.515651482637;5883.852903677443;7280.927190728236;7322.633868306457;7347.26527347261;7358.11702595692;7853.37173026168;7868.960655196687;7886.645100970355;7923.84152316962;7924.84150317002;4648.976755116203;4705.788239529298;4706.199946009118;4708.599769019672;4719.551642594085;6506.056621789909;7132.500724949204;7220.350168484862;7448.553086814808;7449.702011919462;7126.322999315057;7169.856602867809;7171.175314838861;7179.533330333512;7184.928150718426;7114.466415018997;7149.678264478007;7155.141383034069;7167.068281123421;7167.104111986002;6968.547044442095;6979.40675042627;6990.965045174741;7010.75462358815;7011.824704382431;6870.793876183722;7057.329553692391;7142.428605711634;7165.570065796069;7193.568385896863;4860;6282.926634210791;6390.753803008554;6516.609003459807;7024.438044956493 03:30:00 INFO - PROCESS | 1673 | |5;yui.html;661.6856992928357;703.8170075780297;725.0682872508827;755.7921571567822;760.1220590218298;14737;14747.631309217275;14831.851681483182;14962.925185374072;14971.850281497182;13546.932265338684;13737.038407311493;13759.58721238362;13969.511067112653;13999.090059146149;744.3301029073823;912.3066469483174;913.3287034029983;931.2922179143832;932.7201839448172;921.9124183202586;936.8173206224789;939.0468674295596;939.802641445294;942.0391200975001;870.6256309786789;969.2827307792254;990.3067852503241;990.5146478225689;995.59678330276;959.0457494792656;983.5672304186136;984.2962281968427;986.2898712926717;986.6201512417699;713.4043074033189;731.0496354738818;735.1692587376231;735.6799792090404;736.4108713029608;14665.926670366687;14667.413303467849;14760;14831.035982661093;14847.925760371128;12554.623361299176;12604.369781510979;12783.744325113446;12788.680282992907;12879.871201288055;9976.95011524945;9984.400935943866;10089.445080520569;10333.896661033368;10413.16694664425;3499.9825000874835;3524.22467057247;3546.3971124908826;3596.460530920383;3752.6997840172994;56791;76091;77204;77830.22169778286;78187;9532.07959112779;9577.666001964333;9635.903640963641;9699.369540979813;9755.219582433388;21744.34766956994;21819.34541963712;21848.453788655093;21850.562988740454;21902.890485547472;5968.283805943314;6467;7282.745103921443;7481.438892083115;7508.249175082448;4052.3718823581803;4104.42538044674;4110.219058378899;4116.423700681907;4123.690723195772;3216.632931004314;3227.6288226853744;3413.112590726429;3466.532018177564;3478.89563313101;494.49067460515727;561.8876224755065;572.9828105156852;575.0569066730566;576.0696475192534;517.3042258162765;564.3989151553583;607.6616920354663;615.547572534187;629.785872803249;7802.414818888606;8105.51366917975;8116.107228204893;8131.105578386373;8407.327413807003;524.8504176309733;524.8819015721508;592.2005292854698;624.9251287785003;627.2723640577;3808.9428658570146;3836.366999445116;3881.7476864003747;3911.765294082364;3974.5429275633096;86904.13095869088;87854;88720.5563972176;89363.10636893677;89387.55306223428;72281.55436891338;72664;73111;73429.6328518354;75106.87339689909;65873.67063164749;66004.66997665077;66261;67064.98805035857;67396.98904516436;7695.153533111466;7743.264389882952;7836.529808211526;8146.796330091795;8325.750227493185;42288.365674515524;42557.57442425536;43973;44070.559294407925;44373.77813110914;62198;65312.346876530624;65737.01394478991;66533.3346666527;67064.32935670581;8407.95796021016;10639.787204256027;10863.022327990519;10990.021796076782;11105.444727763417;11840.881591184323;11966.70083247891;12010.159288850045;12122.333271670142;12122.575709850113;10919.181061420266;11156.44217789091;11186.161037922026;11401.543938242708;11853.873881981199;10973.780524389627;11069.667909962714;11081.224314297922;11272.66182014541;11909.987651049758;10367.844482332923;11138.443077846237;11173.273737207055;11350.772984540428;11846.881531184577;9673.806523869624;9723.31936764448;9845.753856153653;9895.851562226717;10157.9492102539;8115.675372985014;9503.329633740332;9697.806043878942;9731.891645122483;9772.755681108029 03:30:00 INFO - PROCESS | 1673 | __end_tp_report 03:30:00 INFO - PROCESS | 1673 | __start_cc_report 03:30:00 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,417 03:30:00 INFO - PROCESS | 1673 | __end_cc_report 03:30:00 INFO - PROCESS | 1673 | __startTimestamp1464431400811__endTimestamp 03:30:00 INFO - PROCESS | 1673 | 03:30:00 INFO - PROCESS | 1673 | ------- Summary: start ------- 03:30:00 INFO - PROCESS | 1673 | Number of tests: 6 03:30:00 INFO - PROCESS | 1673 | 03:30:00 INFO - PROCESS | 1673 | [#0] dojo.html Cycles:165 Average:5693.31 Median:5798.39 stddev:648.30 (11.2%) stddev-sans-first:625.46 03:30:00 INFO - PROCESS | 1673 | Values: 3427.9 3450.2 3463.0 3486.4 3495.7 5666.0 5697.4 5698.5 5720.8 5773.8 5725.7 5748.8 5755.3 5755.7 5784.0 5180.3 5225.1 5271.6 5297.9 5298.0 4365.6 5169.9 5326.0 5369.6 5373.1 5212.7 5288.0 5303.0 5319.5 5331.6 5287.2 5313.1 5316.1 5322.5 5354.7 4511.7 5033.4 5097.3 5227.6 5250.0 5466.0 5512.1 5515.0 5587.2 5605.7 6161.7 6205.6 6243.9 6246.9 6252.2 5303.5 6216.2 6220.8 6223.9 6264.9 5776.5 5786.5 5790.4 5798.4 5810.4 6282.8 6290.6 6292.0 6292.8 6300.5 4734.8 5449.6 5685.2 5754.8 6101.8 5254.2 6250.4 6268.5 6283.6 6293.3 5090.3 5142.4 6062.7 6113.5 6247.1 6164.1 6207.0 6243.3 6270.2 6277.3 4913.0 4976.6 4978.3 4981.9 4984.6 4263.0 4400.7 4403.4 4476.2 4481.3 5603.1 5696.2 5730.1 5833.1 5861.1 6208.4 6209.6 6265.2 6268.5 6272.2 5580.7 5662.2 5751.4 5816.3 5870.6 4791.6 5389.4 5427.3 5432.7 5451.2 6336.1 6347.0 6373.5 6373.8 6375.3 6141.0 6207.0 6276.9 6281.2 6302.4 6349.3 6350.0 6350.3 6364.2 6366.8 6176.3 6195.8 6197.6 6199.9 6209.5 6171.7 6225.2 6265.7 6279.8 6284.5 6077.0 6081.4 6112.7 6125.3 6131.3 5933.9 6114.7 6121.8 6149.8 6163.3 4893.6 5080.4 5448.6 5790.6 6047.3 6063.8 6072.4 6072.8 6079.4 6085.1 5794.5 5967.3 5979.8 5995.7 5998.2 03:30:00 INFO - PROCESS | 1673 | 03:30:00 INFO - PROCESS | 1673 | [#1] ext.html Cycles:180 Average:55608.94 Median:38473.12 stddev:113111.32 (294.0%) stddev-sans-first:113353.01 03:30:00 INFO - PROCESS | 1673 | Values: 523.7 525.3 555.9 581.6 606.2 37656.0 39226.0 39259.8 39264.0 39290.4 39807.0 39877.8 40290.6 40432.6 40509.2 9351.9 9540.9 9605.1 9630.9 9633.3 9436.4 9586.9 9641.7 9660.4 9733.5 9637.1 9664.0 9675.6 9700.9 9744.4 9261.8 9618.7 9668.0 9674.2 9681.4 9551.1 9656.0 9673.9 9688.1 9698.9 39624.2 39850.6 39935.6 39974.2 40116.0 2181.7 2640.7 3076.6 3082.1 3254.5 29232.9 29364.0 29522.9 30387.2 31488.7 2477.2 2765.6 2779.1 2788.8 2806.9 328437.0 388883.0 390378.0 396960.0 397249.0 37714.8 38515.4 67247.0 68375.3 68457.0 36891.0 37052.9 37086.9 37129.9 37369.6 14206.2 14245.4 14350.2 14355.6 14397.4 11487.4 11639.5 13377.7 13401.9 13518.1 6865.5 8227.0 8512.7 8557.4 8597.0 3304.5 3328.2 3505.6 3510.2 3512.8 935.4 966.9 1073.2 1101.7 1129.9 27626.0 28574.7 30466.2 30609.1 30623.8 1048.5 1077.6 1090.4 1120.7 1160.6 12270.1 15010.2 15072.8 15144.2 15378.0 286055.0 287176.0 288887.0 289335.6 290201.0 55255.2 61482.0 62494.0 63124.0 63435.0 1495.1 1502.1 1509.4 1510.7 1511.8 28679.1 29220.7 29591.9 29996.4 30201.4 53096.0 53552.0 53713.7 53767.0 53808.0 544735.0 558891.0 561310.0 563466.0 563561.0 28173.6 28283.3 28504.6 28563.7 28647.6 46152.3 46293.3 47292.3 47857.8 48097.0 38617.2 38787.8 38879.6 39340.0 39802.4 37942.4 38054.0 38430.8 38540.0 39119.4 32851.7 37607.0 38636.0 39134.0 39201.4 30623.8 31290.7 32454.2 34427.8 34623.0 21406.0 24862.4 25189.0 25505.0 25556.4 03:30:00 INFO - PROCESS | 1673 | 03:30:00 INFO - PROCESS | 1673 | [#2] jquery.html Cycles:180 Average:34075.83 Median:11692.23 stddev:96932.39 (829.0%) stddev-sans-first:97171.31 03:30:00 INFO - PROCESS | 1673 | Values: 392.9 397.2 398.5 400.6 401.6 19748.7 19911.8 20352.2 20412.1 21265.4 20570.8 20687.9 20933.4 21065.3 21094.3 5733.4 5777.1 6076.8 6079.8 6083.2 5866.1 5873.4 5981.3 6017.9 6050.8 6117.4 6128.4 6163.2 6164.1 6168.6 5676.5 6111.4 6115.8 6128.8 6136.5 4983.7 5764.4 5784.9 5788.4 5799.0 22731.7 25079.0 25500.0 25647.0 25858.0 2032.3 2119.1 2168.6 2245.2 2704.8 9834.1 12836.4 12863.4 14242.6 14750.7 1746.9 1752.2 1862.9 1902.3 1930.8 106099.5 106877.4 110723.0 114535.0 119437.0 9764.6 11574.0 11839.9 11845.8 12049.3 15233.5 15234.9 15261.0 15283.8 15311.5 8764.3 8861.7 8997.5 9051.2 9099.5 7531.7 9094.0 9223.8 9275.6 9426.5 4726.8 4749.7 4756.3 4795.0 4795.4 1998.7 2019.8 2030.4 2051.7 2091.4 828.1 923.3 934.6 969.3 1020.4 19490.7 20582.2 20605.1 20668.7 20775.4 1001.6 1026.8 1027.2 1031.0 1033.5 10650.7 11037.2 11077.3 11094.0 11114.2 501139.0 552599.0 599689.0 622003.0 624139.0 104178.5 114944.9 121237.0 129069.0 137965.0 873.3 996.1 1040.9 1042.0 1053.4 17669.0 17808.4 17873.7 17884.9 18247.5 40235.8 43361.0 44742.6 46267.6 55926.0 71731.0 75428.2 77496.2 81983.6 84525.6 7891.7 8123.3 8160.9 8246.5 8328.6 8949.3 11712.7 12365.9 12738.7 12750.3 10584.6 10757.5 10940.3 11045.5 11123.3 8748.8 10650.6 10797.2 10815.4 11040.6 10922.4 11095.4 11390.7 11424.1 11452.8 8139.7 8247.4 8272.6 8466.8 11227.6 9640.7 11260.8 11722.0 11738.9 12011.9 03:30:00 INFO - PROCESS | 1673 | 03:30:00 INFO - PROCESS | 1673 | [#3] mootools.html Cycles:180 Average:5635.10 Median:4913.43 stddev:3125.66 (63.6%) stddev-sans-first:3118.37 03:30:00 INFO - PROCESS | 1673 | Values: 1418.6 1611.8 1800.3 1891.2 1949.3 7896.1 7912.0 8731.0 8951.8 8960.3 7389.6 8635.6 8773.3 8786.1 8802.3 2371.3 2392.3 2399.4 2403.9 2407.8 2385.9 2411.2 2473.3 2480.5 2482.7 2446.0 2447.6 2465.2 2471.1 2475.0 2405.2 2417.9 2421.4 2432.6 2447.2 2452.4 2470.2 2473.0 2473.4 2478.0 8953.3 8977.8 8994.4 9000.0 9093.4 5289.1 5401.0 5466.3 5469.3 5476.9 3405.4 3436.4 3463.8 3470.5 3524.6 885.8 1044.2 1059.9 1492.1 1532.9 11377.4 11445.1 11475.7 11481.7 11513.9 4507.8 4585.3 4629.5 4632.8 4634.9 8242.3 8633.0 8761.7 9119.0 9135.5 4773.5 5255.8 5281.1 5336.1 5370.6 4406.6 4804.9 4858.6 4895.6 4917.2 3868.5 3868.9 3944.7 3946.1 3981.2 1114.5 1133.0 1211.0 1215.6 1224.3 841.7 853.0 864.6 935.9 942.0 5603.5 5785.1 5809.5 5861.9 5905.6 848.4 983.2 988.0 992.5 1035.2 3830.8 3905.7 3910.9 3923.9 3978.8 10676.6 10928.6 10941.8 11068.3 11226.3 10897.4 10967.9 11008.9 11019.0 11053.2 8081.6 8468.3 8511.5 8555.5 8611.9 4726.4 5498.5 5587.9 5600.2 5780.2 9528.5 9676.6 9765.8 9774.1 9783.3 9434.1 9641.2 9822.0 9899.6 9955.2 6794.3 7092.0 7312.0 7374.4 7536.7 8048.4 8057.2 8067.5 8070.8 8101.2 7019.5 7149.1 7153.6 7243.0 7305.5 7031.5 7059.9 7076.6 7166.9 7199.0 6731.0 6902.7 6903.7 6909.2 6924.5 4712.4 4756.1 4761.9 4870.2 4885.3 4816.2 4828.0 4852.1 4891.1 4909.7 03:30:00 INFO - PROCESS | 1673 | 03:30:00 INFO - PROCESS | 1673 | [#4] prototype.html Cycles:180 Average:5784.04 Median:6511.33 stddev:1864.47 (28.6%) stddev-sans-first:1823.72 03:30:00 INFO - PROCESS | 1673 | Values: 301.3 301.6 328.9 331.1 333.5 5273.2 6535.8 6576.7 6652.3 6712.3 6610.7 6705.6 6719.9 6732.0 6736.8 4976.2 5009.1 5019.1 5020.7 5034.6 5018.8 5026.2 5028.3 5032.2 5035.4 4958.2 4964.3 4972.7 4977.0 4993.5 4652.3 4807.7 4958.6 4966.3 4972.8 4083.4 4094.2 4100.2 4103.1 4114.9 5013.6 6343.0 6345.3 6354.7 6356.6 5658.0 5660.9 5666.0 5675.6 5708.2 7059.2 7063.4 7074.9 7075.5 7139.9 4421.5 4577.7 4579.4 4589.2 4590.7 5941.6 6011.6 7440.5 7522.1 7547.6 4009.8 4646.4 4651.9 4672.9 4677.5 4708.0 4719.7 4733.2 4740.4 4742.8 5627.5 5629.3 5637.4 5643.0 5667.4 6009.9 6818.5 6933.2 7142.4 7148.6 1642.3 1643.3 1662.7 1663.7 1670.9 955.7 1042.0 1046.8 1061.1 1066.1 6579.8 6650.3 6660.4 6716.1 6752.0 6113.5 7484.0 7496.8 7499.2 7524.2 5362.7 6688.5 6688.9 6731.7 6740.4 3925.7 3966.1 3968.8 3981.9 3982.1 7555.3 7814.9 7850.0 7850.9 7880.1 7686.5 7704.9 7706.3 7708.2 7738.6 7879.4 7882.2 7894.4 7907.1 7949.7 6005.9 7173.3 7380.8 7443.9 7451.5 5883.9 7280.9 7322.6 7347.3 7358.1 7853.4 7869.0 7886.6 7923.8 7924.8 4649.0 4705.8 4706.2 4708.6 4719.6 6506.1 7132.5 7220.4 7448.6 7449.7 7126.3 7169.9 7171.2 7179.5 7184.9 7114.5 7149.7 7155.1 7167.1 7167.1 6968.5 6979.4 6991.0 7010.8 7011.8 6870.8 7057.3 7142.4 7165.6 7193.6 4860.0 6282.9 6390.8 6516.6 7024.4 03:30:00 INFO - PROCESS | 1673 | 03:30:00 INFO - PROCESS | 1673 | [#5] yui.html Cycles:180 Average:17453.85 Median:611.60 stddev:24089.14 (3938.7%) stddev-sans-first:24123.71 03:30:00 INFO - PROCESS | 1673 | Values: 661.7 703.8 725.1 755.8 760.1 14737.0 14747.6 14831.9 14962.9 14971.9 13546.9 13737.0 13759.6 13969.5 13999.1 744.3 912.3 913.3 931.3 932.7 921.9 936.8 939.0 939.8 942.0 870.6 969.3 990.3 990.5 995.6 959.0 983.6 984.3 986.3 986.6 713.4 731.0 735.2 735.7 736.4 14665.9 14667.4 14760.0 14831.0 14847.9 12554.6 12604.4 12783.7 12788.7 12879.9 9977.0 9984.4 10089.4 10333.9 10413.2 3500.0 3524.2 3546.4 3596.5 3752.7 56791.0 76091.0 77204.0 77830.2 78187.0 9532.1 9577.7 9635.9 9699.4 9755.2 21744.3 21819.3 21848.5 21850.6 21902.9 5968.3 6467.0 7282.7 7481.4 7508.2 4052.4 4104.4 4110.2 4116.4 4123.7 3216.6 3227.6 3413.1 3466.5 3478.9 494.5 561.9 573.0 575.1 576.1 517.3 564.4 607.7 615.5 629.8 7802.4 8105.5 8116.1 8131.1 8407.3 524.9 524.9 592.2 624.9 627.3 3808.9 3836.4 3881.7 3911.8 3974.5 86904.1 87854.0 88720.6 89363.1 89387.6 72281.6 72664.0 73111.0 73429.6 75106.9 65873.7 66004.7 66261.0 67065.0 67397.0 7695.2 7743.3 7836.5 8146.8 8325.8 42288.4 42557.6 43973.0 44070.6 44373.8 62198.0 65312.3 65737.0 66533.3 67064.3 8408.0 10639.8 10863.0 10990.0 11105.4 11840.9 11966.7 12010.2 12122.3 12122.6 10919.2 11156.4 11186.2 11401.5 11853.9 10973.8 11069.7 11081.2 11272.7 11910.0 10367.8 11138.4 11173.3 11350.8 11846.9 9673.8 9723.3 9845.8 9895.9 10157.9 8115.7 9503.3 9697.8 9731.9 9772.8 03:30:00 INFO - PROCESS | 1673 | -------- Summary: end -------- 03:30:00 INFO - PROCESS | 1673 | 03:30:01 INFO - TEST-INFO | 1673: exit 0 03:30:01 INFO - TEST-OK | dromaeo_css | took 1111719ms 03:30:01 INFO - TEST-START | kraken 03:30:01 INFO - operating with platform_type : mac_ 03:30:01 INFO - Initialising browser for kraken test... 03:30:01 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpOiii7E/profile http://localhost:49283/getInfo.html) 03:30:04 INFO - PROCESS | 1686 | __metrics Screen width/height:1600/1200 03:30:04 INFO - PROCESS | 1686 | colorDepth:24 03:30:04 INFO - PROCESS | 1686 | Browser inner width/height: 1024/689 03:30:04 INFO - PROCESS | 1686 | __metrics 03:30:04 ERROR - PROCESS | 1686 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 03:30:04 INFO - TEST-INFO | 1686: exit 0 03:30:04 INFO - Browser initialized. 03:30:04 INFO - Running cycle 1/1 for kraken test... 03:30:04 INFO - TEST-INFO | started process 1689 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmpOiii7E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:30:16 INFO - PROCESS | 1689 | RSS: Main: 220991488 03:30:16 INFO - PROCESS | 1689 | 03:30:19 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection) 03:30:20 INFO - PROCESS | 1689 | RSS: Main: 232976384 03:30:20 INFO - PROCESS | 1689 | 03:30:23 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-dft) 03:30:23 INFO - PROCESS | 1689 | RSS: Main: 241901568 03:30:23 INFO - PROCESS | 1689 | 03:30:26 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-fft) 03:30:27 INFO - PROCESS | 1689 | RSS: Main: 246206464 03:30:27 INFO - PROCESS | 1689 | 03:30:29 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator) 03:30:30 INFO - PROCESS | 1689 | RSS: Main: 246423552 03:30:30 INFO - PROCESS | 1689 | 03:30:32 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:30:32 INFO - PROCESS | 1689 | RSS: Main: 246640640 03:30:32 INFO - PROCESS | 1689 | 03:30:35 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom) 03:30:36 INFO - PROCESS | 1689 | RSS: Main: 246964224 03:30:36 INFO - PROCESS | 1689 | 03:30:39 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate) 03:30:39 INFO - PROCESS | 1689 | RSS: Main: 246890496 03:30:39 INFO - PROCESS | 1689 | 03:30:42 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial) 03:30:43 INFO - PROCESS | 1689 | RSS: Main: 248393728 03:30:43 INFO - PROCESS | 1689 | 03:30:45 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:30:45 INFO - PROCESS | 1689 | RSS: Main: 247418880 03:30:45 INFO - PROCESS | 1689 | 03:30:47 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:30:48 INFO - PROCESS | 1689 | RSS: Main: 247390208 03:30:48 INFO - PROCESS | 1689 | 03:30:50 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:30:51 INFO - PROCESS | 1689 | RSS: Main: 247754752 03:30:51 INFO - PROCESS | 1689 | 03:30:53 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:30:54 INFO - PROCESS | 1689 | RSS: Main: 247775232 03:30:54 INFO - PROCESS | 1689 | 03:30:57 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:30:58 INFO - PROCESS | 1689 | RSS: Main: 247836672 03:30:58 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49283/tests/kraken/driver.html?testName=ai-astar) 03:31:00 INFO - PROCESS | 1689 | RSS: Main: 249892864 03:31:00 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | __start_tp_report 03:31:00 INFO - PROCESS | 1689 | _x_x_mozilla_page_load 03:31:00 INFO - PROCESS | 1689 | _x_x_mozilla_page_load_details 03:31:00 INFO - PROCESS | 1689 | |i|pagename|runs| 03:31:00 INFO - PROCESS | 1689 | |0;ai-astar;115;121;107;112;118;118;130;167;170;115 03:31:00 INFO - PROCESS | 1689 | |1;audio-beat-detection;153;123;154;153;130;169;155;155;158;126 03:31:00 INFO - PROCESS | 1689 | |2;audio-dft;197;198;198;204;196;200;202;198;201;198 03:31:00 INFO - PROCESS | 1689 | |3;audio-fft;66;70;80;147;65;82;85;61;128;58 03:31:00 INFO - PROCESS | 1689 | |4;audio-oscillator;79;81;137;77;78;92;119;81;82;125 03:31:00 INFO - PROCESS | 1689 | |5;imaging-gaussian-blur;90;89;89;86;90;87;90;87;89;89 03:31:00 INFO - PROCESS | 1689 | |6;imaging-darkroom;96;96;95;127;150;94;95;95;96;95 03:31:00 INFO - PROCESS | 1689 | |7;imaging-desaturate;98;132;131;134;141;129;133;101;85;76 03:31:00 INFO - PROCESS | 1689 | |8;json-parse-financial;67;65;66;66;68;64;65;79;64;61 03:31:00 INFO - PROCESS | 1689 | |9;json-stringify-tinderbox;65;68;78;87;106;66;97;62;71;94 03:31:00 INFO - PROCESS | 1689 | |10;stanford-crypto-aes;84;82;86;85;84;84;82;85;84;87 03:31:00 INFO - PROCESS | 1689 | |11;stanford-crypto-ccm;129;133;123;145;153;150;136;134;153;147 03:31:00 INFO - PROCESS | 1689 | |12;stanford-crypto-pbkdf2;242;240;194;177;179;178;177;177;176;172 03:31:00 INFO - PROCESS | 1689 | |13;stanford-crypto-sha256-iterative;69;71;69;69;73;90;94;97;81;61 03:31:00 INFO - PROCESS | 1689 | __end_tp_report 03:31:00 INFO - PROCESS | 1689 | __start_cc_report 03:31:00 INFO - PROCESS | 1689 | _x_x_mozilla_cycle_collect,1096 03:31:00 INFO - PROCESS | 1689 | __end_cc_report 03:31:00 INFO - PROCESS | 1689 | __startTimestamp1464431460353__endTimestamp 03:31:00 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | ------- Summary: start ------- 03:31:00 INFO - PROCESS | 1689 | Number of tests: 14 03:31:00 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | [#0] ai-astar Cycles:10 Average:127.30 Median:119.50 stddev:22.53 (18.9%) stddev-sans-first:23.45 03:31:00 INFO - PROCESS | 1689 | Values: 115.0 121.0 107.0 112.0 118.0 118.0 130.0 167.0 170.0 115.0 03:31:00 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | [#1] audio-beat-detection Cycles:10 Average:147.60 Median:154.50 stddev:15.48 (10.0%) stddev-sans-first:16.29 03:31:00 INFO - PROCESS | 1689 | Values: 153.0 123.0 154.0 153.0 130.0 169.0 155.0 155.0 158.0 126.0 03:31:00 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | [#2] audio-dft Cycles:10 Average:199.20 Median:199.00 stddev:2.49 (1.2%) stddev-sans-first:2.51 03:31:00 INFO - PROCESS | 1689 | Values: 197.0 198.0 198.0 204.0 196.0 200.0 202.0 198.0 201.0 198.0 03:31:00 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | [#3] audio-fft Cycles:10 Average:84.20 Median:68.00 stddev:29.84 (43.9%) stddev-sans-first:30.91 03:31:00 INFO - PROCESS | 1689 | Values: 66.0 70.0 80.0 147.0 65.0 82.0 85.0 61.0 128.0 58.0 03:31:00 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:80.00 stddev:22.80 (28.5%) stddev-sans-first:23.43 03:31:00 INFO - PROCESS | 1689 | Values: 79.0 81.0 137.0 77.0 78.0 92.0 119.0 81.0 82.0 125.0 03:31:00 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | [#5] imaging-gaussian-blur Cycles:10 Average:88.60 Median:89.00 stddev:1.43 (1.6%) stddev-sans-first:1.42 03:31:00 INFO - PROCESS | 1689 | Values: 90.0 89.0 89.0 86.0 90.0 87.0 90.0 87.0 89.0 89.0 03:31:00 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | [#6] imaging-darkroom Cycles:10 Average:103.90 Median:95.00 stddev:19.03 (20.0%) stddev-sans-first:19.97 03:31:00 INFO - PROCESS | 1689 | Values: 96.0 96.0 95.0 127.0 150.0 94.0 95.0 95.0 96.0 95.0 03:31:00 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | [#7] imaging-desaturate Cycles:10 Average:116.00 Median:137.50 stddev:23.57 (17.1%) stddev-sans-first:24.08 03:31:00 INFO - PROCESS | 1689 | Values: 98.0 132.0 131.0 134.0 141.0 129.0 133.0 101.0 85.0 76.0 03:31:00 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | [#8] json-parse-financial Cycles:10 Average:66.50 Median:66.00 stddev:4.79 (7.3%) stddev-sans-first:5.08 03:31:00 INFO - PROCESS | 1689 | Values: 67.0 65.0 66.0 66.0 68.0 64.0 65.0 79.0 64.0 61.0 03:31:00 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | [#9] json-stringify-tinderbox Cycles:10 Average:79.40 Median:74.50 stddev:15.56 (20.9%) stddev-sans-first:15.61 03:31:00 INFO - PROCESS | 1689 | Values: 65.0 68.0 78.0 87.0 106.0 66.0 97.0 62.0 71.0 94.0 03:31:00 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:84.50 stddev:1.57 (1.9%) stddev-sans-first:1.66 03:31:00 INFO - PROCESS | 1689 | Values: 84.0 82.0 86.0 85.0 84.0 84.0 82.0 85.0 84.0 87.0 03:31:00 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | [#11] stanford-crypto-ccm Cycles:10 Average:140.30 Median:146.00 stddev:10.66 (7.3%) stddev-sans-first:10.49 03:31:00 INFO - PROCESS | 1689 | Values: 129.0 133.0 123.0 145.0 153.0 150.0 136.0 134.0 153.0 147.0 03:31:00 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.20 Median:178.50 stddev:26.87 (15.1%) stddev-sans-first:21.30 03:31:00 INFO - PROCESS | 1689 | Values: 242.0 240.0 194.0 177.0 179.0 178.0 177.0 177.0 176.0 172.0 03:31:00 INFO - PROCESS | 1689 | 03:31:00 INFO - PROCESS | 1689 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.40 Median:77.00 stddev:12.35 (16.0%) stddev-sans-first:12.72 03:31:00 INFO - PROCESS | 1689 | Values: 69.0 71.0 69.0 69.0 73.0 90.0 94.0 97.0 81.0 61.0 03:31:00 INFO - PROCESS | 1689 | -------- Summary: end -------- 03:31:00 INFO - PROCESS | 1689 | 03:31:01 INFO - TEST-INFO | 1689: exit 0 03:31:01 INFO - TEST-OK | kraken | took 59908ms 03:31:01 INFO - SUITE-END | took 1171s 03:31:01 INFO - Completed test suite (00:19:31) 03:31:01 INFO - javascript benchmark 03:31:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3427.897163085107, 3450.2064525159212, 3462.9826850865743, 3486.389881770691, 3495.685388315053, 5665.971670141654, 5697.4302569743, 5698.544116470683, 5720.771169153234, 5773.826785196451, 5725.685087320196, 5748.770049198038, 5755.309362876461, 5755.740991655375, 5783.971080144604, 5180.300659410978, 5225.137852254374, 5271.604629652772, 5297.947020529784, 5298.046351656701, 4365.607095361417, 5169.870753231162, 5325.988062268176, 5369.62412631116, 5373.059714549958, 5212.687238765668, 5288.0, 5302.973485132569, 5319.494648008433, 5331.626786124973, 5287.180487024537, 5313.149896016638, 5316.069687804618, 5322.467753224647, 5354.678719276856, 4511.669057628008, 5033.446320904697, 5097.3118628984985, 5227.555657769056, 5249.973750131263, 5465.9726701366235, 5512.145617429305, 5515.0, 5587.161925711135, 5605.691686957216, 6161.7227224775, 6205.565610407273, 6243.906341404882, 6246.87506249877, 6252.155958945529, 5303.522682958553, 6216.222972128484, 6220.813375598739, 6223.87552248957, 6264.906026409608, 5776.451237132465, 5786.508146807484, 5790.392008839095, 5798.391168927286, 5810.3899090595305, 6282.811515654537, 6290.559660823698, 6291.96854015727, 6292.811215663446, 6300.495960323163, 4734.786934587952, 5449.618526703172, 5685.1756495307945, 5754.798582049608, 6101.84745381369, 5254.211868219798, 6250.4374606285655, 6268.4671802896355, 6283.622982621057, 6293.30773614902, 5090.287359769635, 5142.357205349332, 6062.696865156725, 6113.541484388688, 6247.094171345129, 6164.137020817089, 6206.968965155145, 6243.344448832898, 6270.184875966186, 6277.309495955441, 4913.0, 4976.62675299354, 4978.278149668277, 4981.850544483599, 4984.551390374883, 4263.0, 4400.73595584266, 4403.361512580658, 4476.194285028726, 4481.260592002348, 5603.075492543685, 5696.174054762037, 5730.08318669011, 5833.066709326487, 5861.062230043172, 6208.441240288395, 6209.59637623553, 6265.154204182468, 6268.49852011838, 6272.153259310026, 5580.748866301026, 5662.235598194274, 5751.367349591542, 5816.272965879265, 5870.559708021916, 4791.616670666338, 5389.380221274603, 5427.3215848019, 5432.728363581805, 5451.236826844245, 6336.081268216175, 6346.9682651586445, 6373.458256048102, 6373.840653983688, 6375.266844312778, 6140.964409184426, 6207.037909124093, 6276.905846412399, 6281.183446151971, 6302.376133723036, 6349.301576826453, 6349.9682501587195, 6350.333215012357, 6364.172657554488, 6366.77716279928, 6176.258848937977, 6195.81412557624, 6197.566170368031, 6199.938000620116, 6209.534284928739, 6171.660558669137, 6225.159603453657, 6265.718042687999, 6279.843003924756, 6284.497240220863, 6076.969615151896, 6081.361457046946, 6112.72492737819, 6125.296497837281, 6131.316864617588, 5933.940660593339, 6114.663693496723, 6121.84695382619, 6149.784757533644, 6163.291221509584, 4893.584045356185, 5080.415752188545, 5448.591355648421, 5790.623609465371, 6047.304559975483, 6063.848403789941, 6072.423119803611, 6072.757089716539, 6079.392060793885, 6085.148079268817, 5794.536437084938, 5967.313758917607, 5979.820605381846, 5995.730192141451, 5998.160257563857], "value": 5654.672560039468, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [523.72766161596, 525.3459442993474, 555.8999380111578, 581.5754499215573, 606.2391698418484, 37656.0, 39226.019349516275, 39259.80370098146, 39264.0, 39290.41064384037, 39807.00482487939, 39877.80061099691, 40290.59709402905, 40432.59567404325, 40509.18981620373, 9351.859722104175, 9540.856887146698, 9605.087516685939, 9630.90369096307, 9633.27750418718, 9436.433813971149, 9586.856197157049, 9641.662541811038, 9660.420374777501, 9733.464659443727, 9637.132658060773, 9663.95168024159, 9675.564599593035, 9700.902990970071, 9744.415335079882, 9261.814763704755, 9618.663346782863, 9667.984861589503, 9674.226061915066, 9681.41911485306, 9551.0926461986, 9655.951720241424, 9673.90326096737, 9688.128068473801, 9698.903010969942, 39624.20751584981, 39850.60149398498, 39935.60064399348, 39974.20051598981, 40116.0, 2181.7490988536365, 2640.735926407363, 3076.615423072116, 3082.1370016395445, 3254.495553189259, 29232.853835730686, 29364.0, 29522.852385738148, 30387.240318991982, 31488.685113148804, 2477.2320580620067, 2765.5713364428343, 2779.138467075213, 2788.8047836651444, 2806.915792526207, 328437.0, 388883.0, 390378.0, 396960.0, 397249.01375493227, 37714.81142594324, 38515.42226866599, 67246.99129513062, 68375.3162468369, 68456.97314540287, 36891.0, 37052.888413347646, 37086.887393378245, 37129.88610341694, 37369.62630373716, 14206.21865797391, 14245.430182792572, 14350.210738409487, 14355.641108972359, 14397.352119154662, 11487.368194749202, 11639.486866707273, 13377.73244535104, 13401.86598134006, 13518.121322114031, 6865.519413640997, 8227.0, 8512.659493620185, 8557.44376615518, 8597.0, 3304.454764963754, 3328.1513214130728, 3505.5793304803587, 3510.227749895019, 3512.7892326459983, 935.4200395754675, 966.9315406475743, 1073.2058276875057, 1101.6805126513382, 1129.949152288149, 27626.0, 28574.714252857622, 30466.238344041576, 30609.081727548208, 30623.846880765454, 1048.5124417146064, 1077.5743581285349, 1090.4275255490932, 1120.6638008597552, 1160.6344001639457, 12270.073356953975, 15010.24948752558, 15072.773908391382, 15144.242787860563, 15378.00042997224, 286055.0, 287176.0, 288887.0, 289335.55332223204, 290201.0, 55255.17117243164, 61482.0, 62494.0, 63124.0, 63435.0, 1495.1178804505394, 1502.128765316136, 1509.3660662521768, 1510.7431736604804, 1511.8261399938929, 28679.139625811258, 29220.7077929218, 29591.85204074052, 29996.400071998003, 30201.395972080874, 53096.0, 53552.0, 53713.731431342596, 53767.0, 53808.0, 544735.0, 558891.0, 561310.0, 563466.0, 563561.0, 28173.591320434305, 28283.2929176764, 28504.57243141313, 28563.714362856106, 28647.5702864453, 46152.30771538363, 46293.30560041535, 47292.29061564148, 47857.76071119622, 48097.0, 38617.21999452091, 38787.80606096952, 38879.63921262742, 39340.0, 39802.40296395614, 37942.43086353762, 38054.0, 38430.80784596059, 38540.03649908775, 39119.41320880132, 32851.67148328486, 37607.0, 38636.03409914775, 39134.02164945899, 39201.41197881977, 30623.846880765454, 31290.687093128778, 32454.188645283113, 34427.82786086053, 34623.0, 21406.0, 24862.37844053913, 25189.0, 25505.0, 25556.361090972874], "value": 18098.95865293473, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.8958825911134, 397.24523405529493, 398.48794299325374, 400.6474302613701, 401.6184624606624, 19748.70376944347, 19911.800881991177, 20352.18591256348, 20412.081456334465, 21265.36203913885, 20570.794292057075, 20687.862167580777, 20933.371998840023, 21065.26271580495, 21094.261700840434, 5733.397993210715, 5777.104548794943, 6076.756929722806, 6079.756809727605, 6083.239595050619, 5866.061430171173, 5873.383294754053, 5981.342052374235, 6017.939820601781, 6050.818475445722, 6117.44942955134, 6128.448439640431, 6163.167972323746, 6164.07538869171, 6168.629882207059, 5676.545876329884, 6111.358307377728, 6115.755369785204, 6128.846778830521, 6136.539759518009, 4983.651144419856, 5764.423557644244, 5784.942150578482, 5788.42115788422, 5798.971005144989, 22731.659025114637, 25079.0, 25499.980000799947, 25647.0, 25858.0, 2032.3293313206607, 2119.0993827623197, 2168.566286742642, 2245.2141750387395, 2704.7971402144917, 9834.114929656365, 12836.422360993778, 12863.356832158448, 14242.572722818331, 14750.704985900222, 1746.8645380502649, 1752.220261983418, 1862.9068546572616, 1902.258119333461, 1930.806919308072, 106099.46950265276, 106877.39683904748, 110723.0, 114535.0, 119437.0, 9764.609415623438, 11573.974554452938, 11839.88160118405, 11845.822312665317, 12049.27704337725, 15233.542993709985, 15234.93355465129, 15261.008034477723, 15283.770743438858, 15311.46409875649, 8764.298856091498, 8861.689840855539, 8997.505137217386, 9051.230645395084, 9099.454032758056, 7531.661075251627, 9093.954530227438, 9223.76940576491, 9275.62897484093, 9426.481543515036, 4726.834560790356, 4749.667523273338, 4756.334113224151, 4795.0, 4795.352627395327, 1998.7201791749167, 2019.8485113616243, 2030.3908827351736, 2051.7332746743127, 2091.351680978901, 828.1180542721982, 923.3259720404143, 934.5747684803398, 969.2585172343163, 1020.4030642074321, 19490.707639385422, 20582.176712931614, 20605.072771725307, 20668.689969650466, 20775.37673869786, 1001.5643195210107, 1026.825439675257, 1027.2295778166376, 1030.994845025785, 1033.478093562761, 10650.680479585624, 11037.227394082498, 11077.279976801483, 11094.00153986145, 11114.19728592967, 501139.0, 552599.0, 599689.0, 622003.0, 624139.0, 104178.48646819372, 114944.85055149341, 121237.0, 129069.0, 137965.0, 873.3362644390309, 996.0537489385113, 1040.869891263592, 1041.9843702344472, 1053.4206186597187, 17669.028203448936, 17808.3767068152, 17873.73189402186, 17884.91057544704, 18247.54381140429, 40235.79292621226, 43361.0, 44742.55257447384, 46267.6119716409, 55926.0, 71731.0, 75428.24571754212, 77496.22503775115, 81983.59008204921, 84525.57737211275, 7891.684332626631, 8123.268905798506, 8160.918390816016, 8246.546439945621, 8328.625211865601, 8949.284057275532, 11712.70718232051, 12365.876341236473, 12738.68153296175, 12750.298733569372, 10584.629537966135, 10757.515911783832, 10940.288881222696, 11045.502952367324, 11123.27698699582, 8748.78128046804, 10650.57397704083, 10797.244192906735, 10815.35107893529, 11040.558377664804, 10922.399268040017, 11095.389753563632, 11390.715232119263, 11424.143189260672, 11452.828207576726, 8139.674413023413, 8247.381446391424, 8272.627731752205, 8466.788330291793, 11227.55089796399, 9640.662576809778, 11260.817614150395, 11722.0, 11738.94130529342, 12011.879881201427], "value": 9672.395199876144, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.5602463236398, 1611.7985251843522, 1800.279888044782, 1891.1678861301018, 1949.298252629054, 7896.131425543185, 7912.0, 8731.039585645574, 8951.776205594855, 8960.327975401846, 7389.5935723535185, 8635.611397487113, 8773.341999350043, 8786.12138786121, 8802.251808596277, 2371.276760588016, 2392.3301475586863, 2399.4361325088676, 2403.90622266869, 2407.8194135439826, 2385.8807059646947, 2411.1922505960524, 2473.3445636906235, 2480.466699659573, 2482.6772519572432, 2445.987770061156, 2447.596146635803, 2465.1988103866224, 2471.122751423246, 2474.9752502474926, 2405.206281926977, 2417.9274621761288, 2421.418859473731, 2432.62294344376, 2447.229122826304, 2452.448199155194, 2470.234227389497, 2472.9629055564183, 2473.3692908308267, 2477.9504409911883, 8953.328500362433, 8977.832881725333, 8994.41536300145, 8999.955000224956, 9093.363464557484, 5289.100852855024, 5400.973814975181, 5466.34403871534, 5469.316335458067, 5476.890462190774, 3405.3529829332347, 3436.398630239698, 3463.809490478023, 3470.548828652284, 3524.6475352464804, 885.7829831691276, 1044.2272718188485, 1059.9417032063234, 1492.1196494068552, 1532.8620424161768, 11377.431128443628, 11445.141614378956, 11475.713107172387, 11481.655550333504, 11513.884861151366, 4507.797149128297, 4585.2663573828695, 4629.518554062668, 4632.837850675211, 4634.9339651880255, 8242.258196762321, 8633.007204171601, 8761.685747137853, 9119.042500537347, 9135.54322283883, 4773.498782627844, 5255.816046438356, 5281.0758117329315, 5336.092864213093, 5370.597205209625, 4406.647468202536, 4804.855854324376, 4858.562729354375, 4895.6083513318845, 4917.213245880714, 3868.4777555030273, 3868.941965870514, 3944.723869329177, 3946.072672921861, 3981.1838573092446, 1114.4706264524448, 1132.9546818127346, 1211.0372254057868, 1215.5745489078752, 1224.2715584227371, 841.6801615386114, 852.9744107676779, 864.5893200729729, 935.8689783430189, 941.9529023548795, 5603.523700485504, 5785.103308987113, 5809.535237181015, 5861.912071318934, 5905.616134951214, 848.4442690038035, 983.1643103362085, 988.0, 992.4888682328607, 1035.223582313265, 3830.8084595770656, 3905.6875449964505, 3910.8631197907935, 3923.9215215695526, 3978.801059947049, 10676.572937082585, 10928.617498387519, 10941.83587246192, 11068.28056176346, 11226.326420414802, 10897.400642964554, 10967.890321096687, 11008.944955275172, 11019.0, 11053.226274160892, 8081.6363263653275, 8468.280196183394, 8511.531865747444, 8555.486670799772, 8611.913880861237, 4726.385569875894, 5498.45015498447, 5587.8882422352135, 5600.187972744011, 5780.190773291659, 9528.475933823705, 9676.612935482783, 9765.80468390614, 9774.071463210983, 9783.315167938175, 9434.05659434051, 9641.228701703987, 9822.0, 9899.604015839286, 9955.20358371343, 6794.320567943166, 7091.964540177472, 7312.0, 7374.410047196318, 7536.7362142324755, 8048.356131509582, 8057.234562716767, 8067.515949043076, 8070.83858322842, 8101.189881011852, 7019.508634395747, 7149.14210294768, 7153.570785752871, 7242.96378518104, 7305.488615797056, 7031.507794454339, 7059.858802824017, 7076.646167691698, 7166.928330716834, 7199.028131202221, 6730.956701711242, 6902.689378977858, 6903.689333979883, 6909.205441374105, 6924.480663950123, 4712.3873896394625, 4756.143894098957, 4761.9285710713675, 4870.196417591058, 4885.291632713308, 4816.15717249487, 4827.986122914227, 4852.126617208936, 4891.070696567641, 4909.656324057424], "value": 4569.149782717695, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.31000009977146, 301.6229712858926, 328.86187801123526, 331.09280571234814, 333.48310119315073, 5273.1562949927975, 6535.771248006321, 6576.704048317825, 6652.334766523351, 6712.261651218369, 6610.735570577183, 6705.631190284533, 6719.899201511969, 6732.0, 6736.7978960631135, 4976.154053810861, 5009.098362294785, 5019.146745053332, 5020.7489625518765, 5034.647574669774, 5018.849434516952, 5026.170681837492, 5028.346314979048, 5032.245163225509, 5035.446100928895, 4958.206686930092, 4964.304997300363, 4972.726500042496, 4977.029479251547, 4993.475685053071, 4652.325412815157, 4807.663463557552, 4958.603311735071, 4966.329545511346, 4972.801087956477, 4083.4079058536645, 4094.242565125461, 4100.2414553307435, 4103.076807718269, 4114.855980040714, 5013.6490445668815, 6343.0, 6345.270293916166, 6354.650494222816, 6356.586821856565, 5658.0, 5660.858478538028, 5665.971670141664, 5675.602707810455, 5708.1723150143, 7059.188193357778, 7063.434925206023, 7074.929250707479, 7075.504714669975, 7139.893316535945, 4421.4694236691485, 4577.725336479789, 4579.404677391918, 4589.1968905441745, 4590.724556526619, 5941.55438342126, 6011.639301641915, 7440.516366436164, 7522.059742532184, 7547.62261886909, 4009.799510024487, 4646.442426908792, 4651.86044418668, 4672.901868061003, 4677.462091859412, 4707.976460117746, 4719.7404142772475, 4733.195356789354, 4740.3837501124635, 4742.786574604151, 5627.52166065889, 5629.268195134607, 5637.351704553947, 5642.984262832648, 5667.404922483162, 6009.879802403927, 6818.4886133540185, 6933.168019837652, 7142.392896603847, 7148.642567871586, 1642.3184378482795, 1643.2769581384152, 1662.742274947385, 1663.717168081429, 1670.9415170468976, 955.6941778630771, 1042.0, 1046.7801761629992, 1061.0662616897082, 1066.088494337346, 6579.80260592183, 6650.3349665034075, 6660.400563949268, 6716.126903502513, 6751.966240168767, 6113.541484388688, 7484.0, 7496.775096746997, 7499.175090740015, 7524.247575242432, 5362.731863406892, 6688.498362622823, 6688.899666505006, 6731.663416829139, 6740.4270636995425, 3925.685945124383, 3966.1076257841755, 3968.7618742874943, 3981.880543583697, 3982.1438390745548, 7555.282248186413, 7814.921850781533, 7850.0, 7850.921490785133, 7880.133185349607, 7686.500377475446, 7704.8844267336035, 7706.344960678293, 7708.190639982833, 7738.574378409242, 7879.369650427952, 7882.211778822187, 7894.368450523944, 7907.051153861574, 7949.7217597383815, 6005.939940600713, 7173.282671732785, 7380.77857664271, 7443.888341674771, 7451.515651482637, 5883.852903677443, 7280.927190728236, 7322.633868306457, 7347.26527347261, 7358.11702595692, 7853.37173026168, 7868.960655196687, 7886.645100970355, 7923.84152316962, 7924.84150317002, 4648.976755116203, 4705.788239529298, 4706.199946009118, 4708.599769019672, 4719.551642594085, 6506.056621789909, 7132.500724949204, 7220.350168484862, 7448.553086814808, 7449.702011919462, 7126.322999315057, 7169.856602867809, 7171.175314838861, 7179.533330333512, 7184.928150718426, 7114.466415018997, 7149.678264478007, 7155.141383034069, 7167.068281123421, 7167.104111986002, 6968.547044442095, 6979.40675042627, 6990.965045174741, 7010.75462358815, 7011.824704382431, 6870.793876183722, 7057.329553692391, 7142.428605711634, 7165.570065796069, 7193.568385896863, 4860.0, 6282.926634210791, 6390.753803008554, 6516.609003459807, 7024.438044956493], "value": 5159.333043307001, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.6856992928357, 703.8170075780297, 725.0682872508827, 755.7921571567822, 760.1220590218298, 14737.0, 14747.631309217275, 14831.851681483182, 14962.925185374072, 14971.850281497182, 13546.932265338684, 13737.038407311493, 13759.58721238362, 13969.511067112653, 13999.090059146149, 744.3301029073823, 912.3066469483174, 913.3287034029983, 931.2922179143832, 932.7201839448172, 921.9124183202586, 936.8173206224789, 939.0468674295596, 939.802641445294, 942.0391200975001, 870.6256309786789, 969.2827307792254, 990.3067852503241, 990.5146478225689, 995.59678330276, 959.0457494792656, 983.5672304186136, 984.2962281968427, 986.2898712926717, 986.6201512417699, 713.4043074033189, 731.0496354738818, 735.1692587376231, 735.6799792090404, 736.4108713029608, 14665.926670366687, 14667.413303467849, 14760.0, 14831.035982661093, 14847.925760371128, 12554.623361299176, 12604.369781510979, 12783.744325113446, 12788.680282992907, 12879.871201288055, 9976.95011524945, 9984.400935943866, 10089.445080520569, 10333.896661033368, 10413.16694664425, 3499.9825000874835, 3524.22467057247, 3546.3971124908826, 3596.460530920383, 3752.6997840172994, 56791.0, 76091.0, 77204.0, 77830.22169778286, 78187.0, 9532.07959112779, 9577.666001964333, 9635.903640963641, 9699.369540979813, 9755.219582433388, 21744.34766956994, 21819.34541963712, 21848.453788655093, 21850.562988740454, 21902.890485547472, 5968.283805943314, 6467.0, 7282.745103921443, 7481.438892083115, 7508.249175082448, 4052.3718823581803, 4104.42538044674, 4110.219058378899, 4116.423700681907, 4123.690723195772, 3216.632931004314, 3227.6288226853744, 3413.112590726429, 3466.532018177564, 3478.89563313101, 494.49067460515727, 561.8876224755065, 572.9828105156852, 575.0569066730566, 576.0696475192534, 517.3042258162765, 564.3989151553583, 607.6616920354663, 615.547572534187, 629.785872803249, 7802.414818888606, 8105.51366917975, 8116.107228204893, 8131.105578386373, 8407.327413807003, 524.8504176309733, 524.8819015721508, 592.2005292854698, 624.9251287785003, 627.2723640577, 3808.9428658570146, 3836.366999445116, 3881.7476864003747, 3911.765294082364, 3974.5429275633096, 86904.13095869088, 87854.0, 88720.5563972176, 89363.10636893677, 89387.55306223428, 72281.55436891338, 72664.0, 73111.0, 73429.6328518354, 75106.87339689909, 65873.67063164749, 66004.66997665077, 66261.0, 67064.98805035857, 67396.98904516436, 7695.153533111466, 7743.264389882952, 7836.529808211526, 8146.796330091795, 8325.750227493185, 42288.365674515524, 42557.57442425536, 43973.0, 44070.559294407925, 44373.77813110914, 62198.0, 65312.346876530624, 65737.01394478991, 66533.3346666527, 67064.32935670581, 8407.95796021016, 10639.787204256027, 10863.022327990519, 10990.021796076782, 11105.444727763417, 11840.881591184323, 11966.70083247891, 12010.159288850045, 12122.333271670142, 12122.575709850113, 10919.181061420266, 11156.44217789091, 11186.161037922026, 11401.543938242708, 11853.873881981199, 10973.780524389627, 11069.667909962714, 11081.224314297922, 11272.66182014541, 11909.987651049758, 10367.844482332923, 11138.443077846237, 11173.273737207055, 11350.772984540428, 11846.881531184577, 9673.806523869624, 9723.31936764448, 9845.753856153653, 9895.851562226717, 10157.9492102539, 8115.675372985014, 9503.329633740332, 9697.806043878942, 9731.891645122483, 9772.755681108029], "value": 6775.188688188279, "unit": "score"}], "name": "dromaeo_css", "value": 7353.543786099816, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 121.0, 107.0, 112.0, 118.0, 118.0, 130.0, 167.0, 170.0, 115.0], "value": 127.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 123.0, 154.0, 153.0, 130.0, 169.0, 155.0, 155.0, 158.0, 126.0], "value": 147.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 198.0, 198.0, 204.0, 196.0, 200.0, 202.0, 198.0, 201.0, 198.0], "value": 199.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 80.0, 147.0, 65.0, 82.0, 85.0, 61.0, 128.0, 58.0], "value": 84.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 137.0, 77.0, 78.0, 92.0, 119.0, 81.0, 82.0, 125.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 89.0, 86.0, 90.0, 87.0, 90.0, 87.0, 89.0, 89.0], "value": 88.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 127.0, 150.0, 94.0, 95.0, 95.0, 96.0, 95.0], "value": 103.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 132.0, 131.0, 134.0, 141.0, 129.0, 133.0, 101.0, 85.0, 76.0], "value": 116.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 66.0, 68.0, 64.0, 65.0, 79.0, 64.0, 61.0], "value": 66.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 78.0, 87.0, 106.0, 66.0, 97.0, 62.0, 71.0, 94.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 86.0, 85.0, 84.0, 84.0, 82.0, 85.0, 84.0, 87.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 133.0, 123.0, 145.0, 153.0, 150.0, 136.0, 134.0, 153.0, 147.0], "value": 140.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [242.0, 240.0, 194.0, 177.0, 179.0, 178.0, 177.0, 177.0, 176.0, 172.0], "value": 191.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 69.0, 69.0, 73.0, 90.0, 94.0, 97.0, 81.0, 61.0], "value": 77.4, "unit": "score"}], "name": "kraken", "value": 1601.0000000000002, "alertThreshold": 2.0}]} 03:31:01 INFO - Return code: 0 03:31:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:31:01 ERROR - # TBPL SUCCESS # 03:31:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:31:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:31:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:31:01 INFO - 2016-05-28 03:31:01.686 screenresolution[1694:11764] starting screenresolution argv=screenresolution get 03:31:01 INFO - 2016-05-28 03:31:01.689 screenresolution[1694:11764] Display 0: 1600x1200x32@60 03:31:01 INFO - 2016-05-28 03:31:01.695 screenresolution[1695:11767] starting screenresolution argv=screenresolution list 03:31:01 INFO - Available Modes on Display 0 03:31:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:31:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:31:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:31:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:31:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:31:01 INFO - Intel Iris: 03:31:01 INFO - Chipset Model: Intel Iris 03:31:01 INFO - Type: GPU 03:31:01 INFO - Bus: Built-In 03:31:01 INFO - VRAM (Dynamic, Max): 1536 MB 03:31:01 INFO - Vendor: Intel (0x8086) 03:31:01 INFO - Device ID: 0x0a2e 03:31:01 INFO - Revision ID: 0x0009 03:31:01 INFO - Displays: 03:31:01 INFO - Display: 03:31:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:31:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:31:01 INFO - Display Serial Number: 1600x1200 60 03:31:01 INFO - Main Display: Yes 03:31:01 INFO - Mirror: Off 03:31:01 INFO - Online: Yes 03:31:01 INFO - Rotation: Supported 03:31:01 INFO - Return code: 0 03:31:01 INFO - Running post-action listener: _resource_record_post_action 03:31:01 INFO - Running post-run listener: _resource_record_post_run 03:31:02 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 32882176; Write bytes: 660593664; Read time: 329; Write time: 2140 03:31:02 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 237568; Write bytes: 355940352; Read time: 5; Write time: 821 03:31:03 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 31100416; Write bytes: 300473344; Read time: 311; Write time: 1302 03:31:03 INFO - Running post-run listener: _upload_blobber_files 03:31:03 INFO - Blob upload gear active. 03:31:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:31:03 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:03 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:03 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:03 INFO - (blobuploader) - INFO - Open directory for files ... 03:31:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:31:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:31:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:31:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:31:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:31:04 INFO - (blobuploader) - INFO - Done attempting. 03:31:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:31:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:31:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:31:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:31:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:31:05 INFO - (blobuploader) - INFO - Done attempting. 03:31:05 INFO - (blobuploader) - INFO - Iteration through files over. 03:31:05 INFO - Return code: 0 03:31:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:31:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:31:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d883822ded23f39121d6fdbf8ef0e0d250ebcda1b6ec25394c443f11b935844d34dab01a03892417be45bbe8f1b5e21833e9e53b675c3a49cf60bdf3359a4c8b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32b18cbd0bea3ff9632ba012ceb35ac064e41cfa0a6f2eccc32a99d1263827669c59d65ff62f484be3e50985ac70fb1cf9f7f195ae96175f01cd053bec7259e6"} 03:31:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:31:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:31:05 INFO - Contents: 03:31:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d883822ded23f39121d6fdbf8ef0e0d250ebcda1b6ec25394c443f11b935844d34dab01a03892417be45bbe8f1b5e21833e9e53b675c3a49cf60bdf3359a4c8b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32b18cbd0bea3ff9632ba012ceb35ac064e41cfa0a6f2eccc32a99d1263827669c59d65ff62f484be3e50985ac70fb1cf9f7f195ae96175f01cd053bec7259e6"} 03:31:05 INFO - Running post-run listener: copy_logs_to_upload_dir 03:31:05 INFO - Copying logs to upload dir... 03:31:05 INFO - mkdir: /builds/slave/test/build/upload/logs 03:31:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.929908 ========= master_lag: 2.74 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-28 03:31:08.248905) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 03:31:08.252685) ========= 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.xqpFaTIj1k/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.jleJk77zaK/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d883822ded23f39121d6fdbf8ef0e0d250ebcda1b6ec25394c443f11b935844d34dab01a03892417be45bbe8f1b5e21833e9e53b675c3a49cf60bdf3359a4c8b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32b18cbd0bea3ff9632ba012ceb35ac064e41cfa0a6f2eccc32a99d1263827669c59d65ff62f484be3e50985ac70fb1cf9f7f195ae96175f01cd053bec7259e6"} 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.011375 build_url: 'https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d883822ded23f39121d6fdbf8ef0e0d250ebcda1b6ec25394c443f11b935844d34dab01a03892417be45bbe8f1b5e21833e9e53b675c3a49cf60bdf3359a4c8b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32b18cbd0bea3ff9632ba012ceb35ac064e41cfa0a6f2eccc32a99d1263827669c59d65ff62f484be3e50985ac70fb1cf9f7f195ae96175f01cd053bec7259e6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 03:31:08.287869) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:31:08.288170) ========= 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.xqpFaTIj1k/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.jleJk77zaK/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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.005241 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 03:31:08.336334) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 03:31:08.336629) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 03:31:08.336938) ========= ========= Total master_lag: 2.86 =========