builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0282 starttime: 1464516018.83 results: success (0) buildid: 20160529004021 builduid: f1aac556b1aa4f1abc2d74496b3d3628 revision: bfea62662298a8400d559abd40ec7d4d12fbd97d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-29 03:00:18.826187) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-29 03:00:18.826573) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-29 03:00:18.827154) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-29 03:00:18.993458) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 03:00:18.994035) ========= 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.rJuH0u2Cti/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.5IBN9tWPe7/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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-29 03:00:18-- 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% 33.6M=0s 2016-05-29 03:00:19 (33.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.149784 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 03:00:19.166773) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 03:00:19.167070) ========= 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.rJuH0u2Cti/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.5IBN9tWPe7/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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.019661 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 03:00:19.222903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 03:00:19.223277) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bfea62662298a8400d559abd40ec7d4d12fbd97d --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 bfea62662298a8400d559abd40ec7d4d12fbd97d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rJuH0u2Cti/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.5IBN9tWPe7/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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-29 03:00:19,260 truncating revision to first 12 chars 2016-05-29 03:00:19,260 Setting DEBUG logging. 2016-05-29 03:00:19,261 attempt 1/10 2016-05-29 03:00:19,261 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bfea62662298?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-29 03:00:19,929 unpacking tar archive at: mozilla-aurora-bfea62662298/testing/mozharness/ program finished with exit code 0 elapsedTime=0.950928 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 03:00:20.194201) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-29 03:00:20.194586) ========= script_repo_revision: bfea62662298a8400d559abd40ec7d4d12fbd97d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-29 03:00:20.195042) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-29 03:00:20.195322) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-29 03:00:20.211566) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 12 secs) (at 2016-05-29 03:00:20.211986) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rJuH0u2Cti/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.5IBN9tWPe7/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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:00:20 INFO - MultiFileLogger online at 20160529 03:00:20 in /builds/slave/test 03:00:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:00:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:00:20 INFO - {'append_to_log': False, 03:00:20 INFO - 'base_work_dir': '/builds/slave/test', 03:00:20 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:00:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:00:20 INFO - 'branch': 'Mozilla-Aurora', 03:00:20 INFO - 'buildbot_json_path': 'buildprops.json', 03:00:20 INFO - 'config_files': ('talos/mac_config.py',), 03:00:20 INFO - 'default_actions': ('clobber', 03:00:20 INFO - 'read-buildbot-config', 03:00:20 INFO - 'download-and-extract', 03:00:20 INFO - 'populate-webroot', 03:00:20 INFO - 'create-virtualenv', 03:00:20 INFO - 'install', 03:00:20 INFO - 'run-tests'), 03:00:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:00:20 INFO - 'download_minidump_stackwalk': True, 03:00:20 INFO - 'download_symbols': 'ondemand', 03:00:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:00:20 INFO - 'tooltool.py': '/tools/tooltool.py', 03:00:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:00:20 INFO - '/tools/misc-python/virtualenv.py')}, 03:00:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:00:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:00:20 INFO - 'installer_path': 'installer.exe', 03:00:20 INFO - 'log_level': 'info', 03:00:20 INFO - 'log_name': 'talos', 03:00:20 INFO - 'log_to_console': True, 03:00:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:00:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:00:20 INFO - 'opt_config_files': (), 03:00:20 INFO - 'pip_index': False, 03:00:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:00:20 INFO - 'cmd': ('bash', 03:00:20 INFO - '-c', 03:00:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:00:20 INFO - 'enabled': True, 03:00:20 INFO - 'halt_on_failure': False, 03:00:20 INFO - 'name': 'check_screen_resolution'},), 03:00:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:00:20 INFO - 'cmd': ('bash', 03:00:20 INFO - '-c', 03:00:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:00:20 INFO - 'enabled': True, 03:00:20 INFO - 'halt_on_failure': False, 03:00:20 INFO - 'name': 'check_screen_resolution'},), 03:00:20 INFO - 'run_cmd_checks_enabled': True, 03:00:20 INFO - 'sps_profile': False, 03:00:20 INFO - 'sps_profile_interval': 0, 03:00:20 INFO - 'suite': 'dromaeojs', 03:00:20 INFO - 'system_bits': '32', 03:00:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:00:20 INFO - 'title': 't-yosemite-r7-0282', 03:00:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:00:20 INFO - 'use_talos_json': True, 03:00:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:00:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:00:20 INFO - 'work_dir': 'build'} 03:00:20 INFO - ##### 03:00:20 INFO - ##### Running clobber step. 03:00:20 INFO - ##### 03:00:20 INFO - Running pre-action listener: _resource_record_pre_action 03:00:20 INFO - Running main action method: clobber 03:00:20 INFO - rmtree: /builds/slave/test/build 03:00:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:00:20 INFO - Running post-action listener: _resource_record_post_action 03:00:20 INFO - ##### 03:00:20 INFO - ##### Running read-buildbot-config step. 03:00:20 INFO - ##### 03:00:20 INFO - Running pre-action listener: _resource_record_pre_action 03:00:20 INFO - Running main action method: read_buildbot_config 03:00:20 INFO - Using buildbot properties: 03:00:20 INFO - { 03:00:20 INFO - "project": "", 03:00:20 INFO - "product": "firefox", 03:00:20 INFO - "script_repo_revision": "production", 03:00:20 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:00:20 INFO - "repository": "", 03:00:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:00:20 INFO - "buildid": "20160529004021", 03:00:20 INFO - "stage_platform": "macosx64", 03:00:20 INFO - "basedir": "/builds/slave/test", 03:00:20 INFO - "nightly_build": "True", 03:00:20 INFO - "buildnumber": 11, 03:00:20 INFO - "slavename": "t-yosemite-r7-0282", 03:00:20 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 03:00:20 INFO - "platform": "yosemite_r7", 03:00:20 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:00:20 INFO - "branch": "mozilla-aurora", 03:00:20 INFO - "repo_path": "releases/mozilla-aurora", 03:00:20 INFO - "revision": "bfea62662298a8400d559abd40ec7d4d12fbd97d", 03:00:20 INFO - "pgo_build": "False", 03:00:20 INFO - "builduid": "f1aac556b1aa4f1abc2d74496b3d3628", 03:00:20 INFO - "slavebuilddir": "test" 03:00:20 INFO - } 03:00:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:00:20 INFO - Running post-action listener: _resource_record_post_action 03:00:20 INFO - ##### 03:00:20 INFO - ##### Running download-and-extract step. 03:00:20 INFO - ##### 03:00:20 INFO - Running pre-action listener: _resource_record_pre_action 03:00:20 INFO - Running main action method: download_and_extract 03:00:20 INFO - mkdir: /builds/slave/test/build/tests 03:00:20 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:00:20 INFO - https://queue.taskcluster.net/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:00:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:00:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:00:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/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:00:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/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:00:51 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 03:00:51 INFO - retry: attempt #1 caught exception: timed out 03:00:51 INFO - retry: Failed, sleeping 30 seconds before retrying 03:01:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/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 #2 03:01:24 INFO - Downloaded 1199 bytes. 03:01:24 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:01:24 INFO - Using the following test package requirements: 03:01:24 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:01:24 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:01:24 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:01:24 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:01:24 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:01:24 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:01:24 INFO - u'jsshell-mac.zip'], 03:01:24 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:01:24 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:01:24 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:01:24 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:01:24 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:01:24 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:01:24 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:01:24 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:01:24 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:01:24 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:01:24 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:01:24 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:24 INFO - https://queue.taskcluster.net/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:01:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:01:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:01:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/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:01:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/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:01:26 INFO - Downloaded 18425697 bytes. 03:01:26 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:01:26 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:01:30 INFO - Return code: 0 03:01:30 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:01: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:01:30 INFO - https://queue.taskcluster.net/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:01:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:01:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:01:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/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:01:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/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:01:36 INFO - Downloaded 18425697 bytes. 03:01:36 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:01:36 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:01:39 INFO - Return code: 0 03:01:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:39 INFO - https://queue.taskcluster.net/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:01:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:01:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:01:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/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:01:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/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:01:40 INFO - Downloaded 10840145 bytes. 03:01:40 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:01:40 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:01:40 INFO - Return code: 0 03:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:40 INFO - https://queue.taskcluster.net/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:01:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:01:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:01:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 03:01:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/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:01:44 INFO - Downloaded 114235775 bytes. 03:01:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:01:44 INFO - mkdir: /builds/slave/test/properties 03:01:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:01:44 INFO - Writing to file /builds/slave/test/properties/build_url 03:01:44 INFO - Contents: 03:01:44 INFO - build_url:https://queue.taskcluster.net/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:01:45 INFO - Running post-action listener: _resource_record_post_action 03:01:45 INFO - Running post-action listener: set_extra_try_arguments 03:01:45 INFO - ##### 03:01:45 INFO - ##### Running populate-webroot step. 03:01:45 INFO - ##### 03:01:45 INFO - Running pre-action listener: _resource_record_pre_action 03:01:45 INFO - Running main action method: populate_webroot 03:01:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:01:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:01:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:01:45 INFO - '--tppagecycles', 03:01:45 INFO - '7'], 03:01:45 INFO - 'tests': ['tsvgm']}}, 03:01:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:01:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:01:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:01:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:01:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:01:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:01:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:45 INFO - 'talos_options': ['--disable-e10s'], 03:01:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:01:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:01:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:45 INFO - 'talos_options': ['--disable-e10s'], 03:01:45 INFO - 'tests': ['damp', 'tps']}, 03:01:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:45 INFO - 'tests': ['damp', 'tps']}, 03:01:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:01:45 INFO - 'tests': ['dromaeo_dom']}, 03:01:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:01:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:01:45 INFO - 'tests': ['a11yr', 03:01:45 INFO - 'ts_paint', 03:01:45 INFO - 'tpaint', 03:01:45 INFO - 'sessionrestore', 03:01:45 INFO - 'sessionrestore_no_auto_restore', 03:01:45 INFO - 'tabpaint']}, 03:01:45 INFO - 'other-e10s': {'tests': ['a11yr', 03:01:45 INFO - 'ts_paint', 03:01:45 INFO - 'tpaint', 03:01:45 INFO - 'sessionrestore', 03:01:45 INFO - 'sessionrestore_no_auto_restore', 03:01:45 INFO - 'tabpaint']}, 03:01:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:01:45 INFO - 'ts_paint', 03:01:45 INFO - 'tpaint', 03:01:45 INFO - 'sessionrestore', 03:01:45 INFO - 'sessionrestore_no_auto_restore', 03:01:45 INFO - 'tabpaint']}, 03:01:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:01:45 INFO - 'ts_paint', 03:01:45 INFO - 'tpaint', 03:01:45 INFO - 'sessionrestore', 03:01:45 INFO - 'sessionrestore_no_auto_restore', 03:01:45 INFO - 'tabpaint']}, 03:01:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:01:45 INFO - 'tests': ['a11yr', 03:01:45 INFO - 'ts_paint', 03:01:45 INFO - 'tpaint', 03:01:45 INFO - 'sessionrestore', 03:01:45 INFO - 'sessionrestore_no_auto_restore', 03:01:45 INFO - 'tabpaint']}, 03:01:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:01:45 INFO - 'tests': ['a11yr', 03:01:45 INFO - 'ts_paint', 03:01:45 INFO - 'tpaint', 03:01:45 INFO - 'sessionrestore', 03:01:45 INFO - 'sessionrestore_no_auto_restore', 03:01:45 INFO - 'tabpaint']}, 03:01:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:01:45 INFO - 'tests': ['tsvgx', 03:01:45 INFO - 'tsvgr_opacity', 03:01:45 INFO - 'tart', 03:01:45 INFO - 'tscrollx', 03:01:45 INFO - 'cart']}, 03:01:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:01:45 INFO - 'tsvgr_opacity', 03:01:45 INFO - 'tart', 03:01:45 INFO - 'tscrollx', 03:01:45 INFO - 'cart']}, 03:01:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:45 INFO - 'talos_options': ['--disable-e10s'], 03:01:45 INFO - 'tests': ['tp5o']}, 03:01:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:45 INFO - 'tests': ['tp5o']}, 03:01:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:45 INFO - 'talos_options': ['--disable-e10s', 03:01:45 INFO - '--xperf_path', 03:01:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:01:45 INFO - 'tests': ['tp5n']}, 03:01:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:01:45 INFO - 'talos_options': ['--xperf_path', 03:01:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:01:45 INFO - 'tests': ['tp5n']}}, 03:01:45 INFO - 'talos.zip': {'path': '', 03:01:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:01:45 INFO - Running post-action listener: _resource_record_post_action 03:01:45 INFO - ##### 03:01:45 INFO - ##### Running create-virtualenv step. 03:01:45 INFO - ##### 03:01:45 INFO - Running pre-action listener: _resource_record_pre_action 03:01:45 INFO - Running main action method: create_virtualenv 03:01:45 INFO - Creating virtualenv /builds/slave/test/build/venv 03:01:45 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:01:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:01:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:01:45 INFO - Using real prefix '/tools/python27' 03:01:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:01:46 INFO - Installing distribute.............................................................................................................................................................................................done. 03:01:49 INFO - Installing pip.................done. 03:01:49 INFO - Return code: 0 03:01:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:01:49 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:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:49 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:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:49 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 0x10becca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff959d8cc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd6be00>, '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 0x10bbd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd66b20>, '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.rJuH0u2Cti/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.5IBN9tWPe7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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:01:49 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:01:49 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:01:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJuH0u2Cti/Render', 03:01:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:49 INFO - 'HOME': '/Users/cltbld', 03:01:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:49 INFO - 'LOGNAME': 'cltbld', 03:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:49 INFO - 'MOZ_NO_REMOTE': '1', 03:01:49 INFO - 'NO_EM_RESTART': '1', 03:01:49 INFO - 'PAGER': '/bin/cat', 03:01:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:49 INFO - 'PWD': '/builds/slave/test', 03:01:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:49 INFO - 'SHELL': '/bin/bash', 03:01:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IBN9tWPe7/Listeners', 03:01:49 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 03:01:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:49 INFO - 'USER': 'cltbld', 03:01:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:49 INFO - 'XPC_FLAGS': '0x0', 03:01:49 INFO - 'XPC_SERVICE_NAME': '0', 03:01:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:49 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:01:49 INFO - Cleaning up... 03:01:49 INFO - Return code: 0 03:01:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:01:49 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:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:49 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:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:49 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 0x10becca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff959d8cc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd6be00>, '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 0x10bbd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd66b20>, '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.rJuH0u2Cti/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.5IBN9tWPe7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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:01:49 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:01:49 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:01:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJuH0u2Cti/Render', 03:01:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:49 INFO - 'HOME': '/Users/cltbld', 03:01:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:49 INFO - 'LOGNAME': 'cltbld', 03:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:49 INFO - 'MOZ_NO_REMOTE': '1', 03:01:49 INFO - 'NO_EM_RESTART': '1', 03:01:49 INFO - 'PAGER': '/bin/cat', 03:01:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:49 INFO - 'PWD': '/builds/slave/test', 03:01:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:49 INFO - 'SHELL': '/bin/bash', 03:01:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IBN9tWPe7/Listeners', 03:01:49 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 03:01:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:49 INFO - 'USER': 'cltbld', 03:01:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:49 INFO - 'XPC_FLAGS': '0x0', 03:01:49 INFO - 'XPC_SERVICE_NAME': '0', 03:01:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:49 INFO - Downloading/unpacking psutil>=0.7.1 03:01:49 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:01:49 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:01:49 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:01:49 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:01:49 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:01:49 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:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:01:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:01:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:01:53 INFO - Installing collected packages: psutil 03:01:53 INFO - Running setup.py install for psutil 03:01:53 INFO - building 'psutil._psutil_osx' extension 03:01:53 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:01:53 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:01:53 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:01:53 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:01:53 INFO - building 'psutil._psutil_posix' extension 03:01:53 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:01:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:01:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:01:53 INFO - ^ 03:01:53 INFO - 1 warning generated. 03:01:53 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:01:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:01:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:01:53 INFO - Successfully installed psutil 03:01:53 INFO - Cleaning up... 03:01:54 INFO - Return code: 0 03:01:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10becca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff959d8cc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd6be00>, '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 0x10bbd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd66b20>, '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.rJuH0u2Cti/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.5IBN9tWPe7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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:01:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 03:01:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:01:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJuH0u2Cti/Render', 03:01:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:54 INFO - 'HOME': '/Users/cltbld', 03:01:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:54 INFO - 'LOGNAME': 'cltbld', 03:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:54 INFO - 'MOZ_NO_REMOTE': '1', 03:01:54 INFO - 'NO_EM_RESTART': '1', 03:01:54 INFO - 'PAGER': '/bin/cat', 03:01:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:54 INFO - 'PWD': '/builds/slave/test', 03:01:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:54 INFO - 'SHELL': '/bin/bash', 03:01:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IBN9tWPe7/Listeners', 03:01:54 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 03:01:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:54 INFO - 'USER': 'cltbld', 03:01:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:54 INFO - 'XPC_FLAGS': '0x0', 03:01:54 INFO - 'XPC_SERVICE_NAME': '0', 03:01:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:01: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:01: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:01: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:01: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:01: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:01: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:01:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:01:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:01:56 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:01:56 INFO - Installing collected packages: mozsystemmonitor 03:01:56 INFO - Running setup.py install for mozsystemmonitor 03:01:56 INFO - Successfully installed mozsystemmonitor 03:01:56 INFO - Cleaning up... 03:01:56 INFO - Return code: 0 03:01:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:56 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 0x10becca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff959d8cc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd6be00>, '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 0x10bbd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd66b20>, '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.rJuH0u2Cti/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.5IBN9tWPe7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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:01:56 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:01:56 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:01:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJuH0u2Cti/Render', 03:01:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:56 INFO - 'HOME': '/Users/cltbld', 03:01:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:56 INFO - 'LOGNAME': 'cltbld', 03:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:56 INFO - 'MOZ_NO_REMOTE': '1', 03:01:56 INFO - 'NO_EM_RESTART': '1', 03:01:56 INFO - 'PAGER': '/bin/cat', 03:01:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:56 INFO - 'PWD': '/builds/slave/test', 03:01:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:56 INFO - 'SHELL': '/bin/bash', 03:01:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IBN9tWPe7/Listeners', 03:01:56 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 03:01:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:56 INFO - 'USER': 'cltbld', 03:01:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:56 INFO - 'XPC_FLAGS': '0x0', 03:01:56 INFO - 'XPC_SERVICE_NAME': '0', 03:01:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:57 INFO - Downloading/unpacking blobuploader==1.2.4 03:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:59 INFO - Downloading blobuploader-1.2.4.tar.gz 03:01:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:01:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:02:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:02:00 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:02:00 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:02:00 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:02:00 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:02:00 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:02:00 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:02:00 INFO - Downloading docopt-0.6.1.tar.gz 03:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:02:00 INFO - Installing collected packages: blobuploader, requests, docopt 03:02:00 INFO - Running setup.py install for blobuploader 03:02:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:02:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:02:01 INFO - Running setup.py install for requests 03:02:01 INFO - Running setup.py install for docopt 03:02:01 INFO - Successfully installed blobuploader requests docopt 03:02:01 INFO - Cleaning up... 03:02:01 INFO - Return code: 0 03:02:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:01 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 0x10becca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff959d8cc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd6be00>, '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 0x10bbd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd66b20>, '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.rJuH0u2Cti/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.5IBN9tWPe7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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:02:01 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:02:01 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:02:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJuH0u2Cti/Render', 03:02:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:01 INFO - 'HOME': '/Users/cltbld', 03:02:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:01 INFO - 'LOGNAME': 'cltbld', 03:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:01 INFO - 'MOZ_NO_REMOTE': '1', 03:02:01 INFO - 'NO_EM_RESTART': '1', 03:02:01 INFO - 'PAGER': '/bin/cat', 03:02:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:01 INFO - 'PWD': '/builds/slave/test', 03:02:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:01 INFO - 'SHELL': '/bin/bash', 03:02:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IBN9tWPe7/Listeners', 03:02:01 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 03:02:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:01 INFO - 'USER': 'cltbld', 03:02:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:01 INFO - 'XPC_FLAGS': '0x0', 03:02:01 INFO - 'XPC_SERVICE_NAME': '0', 03:02:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:02:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-YVhgzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:02:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-Cc8aBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:02:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-P45pRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:02:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-IAJLIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:02:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-yQ9TkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:02:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-RY84E8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:02:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-KIg9Sx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:02:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-TkGcxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:02:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-WbAezl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:02:02 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-ssNyu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:02:03 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-yL_WdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:02:03 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-9hUgIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:02:03 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-DZe6qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:02:03 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-nxBVVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:02:03 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-4Ptqvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:02:03 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-GVR3GW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:02:03 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-_aUGvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:02:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:02:03 INFO - Running setup.py install for manifestparser 03:02:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:02:03 INFO - Running setup.py install for mozcrash 03:02:04 INFO - Running setup.py install for mozdebug 03:02:04 INFO - Running setup.py install for mozdevice 03:02:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:02:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:02:04 INFO - Running setup.py install for mozfile 03:02:04 INFO - Running setup.py install for mozhttpd 03:02:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:02:04 INFO - Running setup.py install for mozinfo 03:02:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:02:04 INFO - Running setup.py install for mozInstall 03:02:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:02:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:02:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:02:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:02:04 INFO - Running setup.py install for mozleak 03:02:05 INFO - Running setup.py install for mozlog 03:02:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:02:05 INFO - Running setup.py install for moznetwork 03:02:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:02:05 INFO - Running setup.py install for mozprocess 03:02:05 INFO - Running setup.py install for mozprofile 03:02:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:02:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:02:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:02:05 INFO - Running setup.py install for mozrunner 03:02:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:02:05 INFO - Running setup.py install for mozscreenshot 03:02:06 INFO - Running setup.py install for moztest 03:02:06 INFO - Running setup.py install for mozversion 03:02:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:02:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:02:06 INFO - Cleaning up... 03:02:06 INFO - Return code: 0 03:02:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:06 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 0x10becca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff959d8cc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd6be00>, '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 0x10bbd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd66b20>, '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.rJuH0u2Cti/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.5IBN9tWPe7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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:02:06 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:02:06 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:02:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJuH0u2Cti/Render', 03:02:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:06 INFO - 'HOME': '/Users/cltbld', 03:02:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:06 INFO - 'LOGNAME': 'cltbld', 03:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:06 INFO - 'MOZ_NO_REMOTE': '1', 03:02:06 INFO - 'NO_EM_RESTART': '1', 03:02:06 INFO - 'PAGER': '/bin/cat', 03:02:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:06 INFO - 'PWD': '/builds/slave/test', 03:02:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:06 INFO - 'SHELL': '/bin/bash', 03:02:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IBN9tWPe7/Listeners', 03:02:06 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 03:02:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:06 INFO - 'USER': 'cltbld', 03:02:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:06 INFO - 'XPC_FLAGS': '0x0', 03:02:06 INFO - 'XPC_SERVICE_NAME': '0', 03:02:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:02:06 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-gYZN9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:02:06 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:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:02:06 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-f4k84f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:02:06 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:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:02:06 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-KwNvKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:02:06 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:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:02:06 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-XqVtyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:02:07 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:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:02:07 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-Bfdj17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:02:07 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:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:02:07 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-Jt_K6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:02:07 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:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:02:07 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-K3Kfla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:02:07 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:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:02:07 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-_XcHCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:02:07 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:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:02:07 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-anNg7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:02:07 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:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:02:07 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-8y2vnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:02:07 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:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:02:07 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-k7p1iS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:02:07 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:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:02:07 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-S1scMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:02:07 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:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:02:07 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-8ewf4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:02:08 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:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:02:08 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-CjAChW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:02:08 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:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:02:08 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-aONdZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:02:08 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:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:02:08 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-EqbY1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:02:08 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:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:02:08 INFO - Running setup.py (path:/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/pip-l_Nipt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:02:08 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:02:08 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:02:08 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:02:08 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:02:08 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:02:08 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:02:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:02:08 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:02:08 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:02:08 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:02:08 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:02:08 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:02:08 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:02:10 INFO - Downloading blessings-1.6.tar.gz 03:02:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:02:11 INFO - Installing collected packages: blessings 03:02:11 INFO - Running setup.py install for blessings 03:02:11 INFO - Successfully installed blessings 03:02:11 INFO - Cleaning up... 03:02:11 INFO - Return code: 0 03:02:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:02:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:02:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:02:11 INFO - Reading from file tmpfile_stdout 03:02:11 INFO - Current package versions: 03:02:11 INFO - blessings == 1.6 03:02:11 INFO - blobuploader == 1.2.4 03:02:11 INFO - docopt == 0.6.1 03:02:11 INFO - manifestparser == 1.1 03:02:11 INFO - mozInstall == 1.12 03:02:11 INFO - mozcrash == 0.17 03:02:11 INFO - mozdebug == 0.1 03:02:11 INFO - mozdevice == 0.48 03:02:11 INFO - mozfile == 1.2 03:02:11 INFO - mozhttpd == 0.7 03:02:11 INFO - mozinfo == 0.9 03:02:11 INFO - mozleak == 0.1 03:02:11 INFO - mozlog == 3.1 03:02:11 INFO - moznetwork == 0.27 03:02:11 INFO - mozprocess == 0.22 03:02:11 INFO - mozprofile == 0.28 03:02:11 INFO - mozrunner == 6.11 03:02:11 INFO - mozscreenshot == 0.1 03:02:11 INFO - mozsystemmonitor == 0.0 03:02:11 INFO - moztest == 0.7 03:02:11 INFO - mozversion == 1.4 03:02:11 INFO - psutil == 3.1.1 03:02:11 INFO - requests == 1.2.3 03:02:11 INFO - wsgiref == 0.1.2 03:02:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:02:11 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:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:11 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:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:11 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 0x10becca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff959d8cc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd6be00>, '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 0x10bbd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd66b20>, '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.rJuH0u2Cti/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.5IBN9tWPe7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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:02:11 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:02:11 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:02:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJuH0u2Cti/Render', 03:02:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:11 INFO - 'HOME': '/Users/cltbld', 03:02:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:11 INFO - 'LOGNAME': 'cltbld', 03:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:11 INFO - 'MOZ_NO_REMOTE': '1', 03:02:11 INFO - 'NO_EM_RESTART': '1', 03:02:11 INFO - 'PAGER': '/bin/cat', 03:02:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:11 INFO - 'PWD': '/builds/slave/test', 03:02:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:11 INFO - 'SHELL': '/bin/bash', 03:02:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IBN9tWPe7/Listeners', 03:02:11 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 03:02:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:11 INFO - 'USER': 'cltbld', 03:02:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:11 INFO - 'XPC_FLAGS': '0x0', 03:02:11 INFO - 'XPC_SERVICE_NAME': '0', 03:02:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:11 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:02:11 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:02:11 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:02:11 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:02:11 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:02:11 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:02:11 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:02:11 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:02:11 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:02:11 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:02:11 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:02:11 INFO - Cleaning up... 03:02:11 INFO - Return code: 0 03:02:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:02:11 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:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:11 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:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:11 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 0x10becca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff959d8cc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd6be00>, '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 0x10bbd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd66b20>, '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.rJuH0u2Cti/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.5IBN9tWPe7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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:02:11 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:02:11 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:02:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJuH0u2Cti/Render', 03:02:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:11 INFO - 'HOME': '/Users/cltbld', 03:02:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:11 INFO - 'LOGNAME': 'cltbld', 03:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:11 INFO - 'MOZ_NO_REMOTE': '1', 03:02:11 INFO - 'NO_EM_RESTART': '1', 03:02:11 INFO - 'PAGER': '/bin/cat', 03:02:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:11 INFO - 'PWD': '/builds/slave/test', 03:02:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:11 INFO - 'SHELL': '/bin/bash', 03:02:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IBN9tWPe7/Listeners', 03:02:11 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 03:02:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:11 INFO - 'USER': 'cltbld', 03:02:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:11 INFO - 'XPC_FLAGS': '0x0', 03:02:11 INFO - 'XPC_SERVICE_NAME': '0', 03:02:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:12 INFO - Downloading/unpacking jsonschema 03:02:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:02:14 INFO - Downloading/unpacking functools32 (from jsonschema) 03:02:14 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:02:14 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:02:14 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:02:14 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:02:14 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:02:14 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:02:14 INFO - Downloading functools32-3.2.3-2.tar.gz 03:02:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:02:14 INFO - warning: no files found matching '*.txt' 03:02:14 INFO - no previously-included directories found matching 'build' 03:02:14 INFO - no previously-included directories found matching 'dist' 03:02:14 INFO - no previously-included directories found matching '.git*' 03:02:14 INFO - Installing collected packages: jsonschema, functools32 03:02:15 INFO - Running setup.py install for functools32 03:02:15 INFO - warning: no files found matching '*.txt' 03:02:15 INFO - no previously-included directories found matching 'build' 03:02:15 INFO - no previously-included directories found matching 'dist' 03:02:15 INFO - no previously-included directories found matching '.git*' 03:02:15 INFO - Successfully installed jsonschema functools32 03:02:15 INFO - Cleaning up... 03:02:15 INFO - Return code: 0 03:02:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:02: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:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02: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:02:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:02:15 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 0x10becca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bedc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff959d8cc10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd6be00>, '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 0x10bbd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd66b20>, '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.rJuH0u2Cti/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.5IBN9tWPe7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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:02:15 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:02:15 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:02:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJuH0u2Cti/Render', 03:02:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:15 INFO - 'HOME': '/Users/cltbld', 03:02:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:15 INFO - 'LOGNAME': 'cltbld', 03:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:15 INFO - 'MOZ_NO_REMOTE': '1', 03:02:15 INFO - 'NO_EM_RESTART': '1', 03:02:15 INFO - 'PAGER': '/bin/cat', 03:02:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:15 INFO - 'PWD': '/builds/slave/test', 03:02:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:15 INFO - 'SHELL': '/bin/bash', 03:02:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IBN9tWPe7/Listeners', 03:02:15 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 03:02:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:15 INFO - 'USER': 'cltbld', 03:02:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:15 INFO - 'XPC_FLAGS': '0x0', 03:02:15 INFO - 'XPC_SERVICE_NAME': '0', 03:02:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:02:15 INFO - Downloading/unpacking flake8 03:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:02:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:02:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:02:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:02:17 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:02:17 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:02:17 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:02:17 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:02:17 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:02:17 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:02:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:02:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:02:18 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:02:18 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:02:18 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:02:18 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:02:18 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:02:18 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:02:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:02:18 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:02:18 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:02:18 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:02:18 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:02:18 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:02:18 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:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:02:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:02:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:02:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:02:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:02:19 INFO - no previously-included directories found matching 'docs/_build' 03:02:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:02:19 INFO - Running setup.py install for pep8 03:02:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:02:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:02:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:02:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:02:19 INFO - no previously-included directories found matching 'docs/_build' 03:02:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:02:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:02:19 INFO - Cleaning up... 03:02:19 INFO - Return code: 0 03:02:19 INFO - Running post-action listener: _resource_record_post_action 03:02:19 INFO - Running post-action listener: _start_resource_monitoring 03:02:19 INFO - Starting resource monitoring. 03:02:19 INFO - ##### 03:02:19 INFO - ##### Running install step. 03:02:19 INFO - ##### 03:02:19 INFO - Running pre-action listener: _resource_record_pre_action 03:02:19 INFO - Running main action method: install 03:02:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:02:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:02:19 INFO - Reading from file tmpfile_stdout 03:02:19 INFO - Detecting whether we're running mozinstall >=1.0... 03:02:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:02:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:02:20 INFO - Reading from file tmpfile_stdout 03:02:20 INFO - Output received: 03:02:20 INFO - Usage: mozinstall [options] installer 03:02:20 INFO - Options: 03:02:20 INFO - -h, --help show this help message and exit 03:02:20 INFO - -d DEST, --destination=DEST 03:02:20 INFO - Directory to install application into. [default: 03:02:20 INFO - "/builds/slave/test"] 03:02:20 INFO - --app=APP Application being installed. [default: firefox] 03:02:20 INFO - mkdir: /builds/slave/test/build/application 03:02:20 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:02:20 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:02:51 INFO - Reading from file tmpfile_stdout 03:02:51 INFO - Output received: 03:02:51 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:02:51 INFO - Running post-action listener: _resource_record_post_action 03:02:51 INFO - ##### 03:02:51 INFO - ##### Running run-tests step. 03:02:51 INFO - ##### 03:02:51 INFO - Running pre-action listener: _resource_record_pre_action 03:02:51 INFO - Running main action method: run_tests 03:02:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:02:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:02:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:02:51 INFO - 2016-05-29 03:02:51.927 screenresolution[6299:106474] starting screenresolution argv=screenresolution get 03:02:51 INFO - 2016-05-29 03:02:51.930 screenresolution[6299:106474] Display 0: 1600x1200x32@60 03:02:51 INFO - 2016-05-29 03:02:51.936 screenresolution[6300:106478] starting screenresolution argv=screenresolution list 03:02:51 INFO - Available Modes on Display 0 03:02:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:02:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:02:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:02:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:02:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:02:52 INFO - Intel Iris: 03:02:52 INFO - Chipset Model: Intel Iris 03:02:52 INFO - Type: GPU 03:02:52 INFO - Bus: Built-In 03:02:52 INFO - VRAM (Dynamic, Max): 1536 MB 03:02:52 INFO - Vendor: Intel (0x8086) 03:02:52 INFO - Device ID: 0x0a2e 03:02:52 INFO - Revision ID: 0x0009 03:02:52 INFO - Displays: 03:02:52 INFO - Display: 03:02:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:02:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:02:52 INFO - Display Serial Number: 1600x1200 60 03:02:52 INFO - Main Display: Yes 03:02:52 INFO - Mirror: Off 03:02:52 INFO - Online: Yes 03:02:52 INFO - Rotation: Supported 03:02:52 INFO - Return code: 0 03:02:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:02:52 INFO - Python 2.7.3 03:02:52 INFO - Return code: 0 03:02:52 INFO - grabbing minidump binary from tooltool 03:02:52 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:02:52 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 0x10bd6be00>, '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 0x10bbd4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd66b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:02:52 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:02:52 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:02:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:02:52 INFO - Return code: 0 03:02:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:02:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:02:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:02:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:02:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:02:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:02:53 INFO - Return code: 0 03:02:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0282', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:02:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0282 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:02:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJuH0u2Cti/Render', 03:02:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:53 INFO - 'HOME': '/Users/cltbld', 03:02:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:53 INFO - 'LOGNAME': 'cltbld', 03:02:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:02:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:02:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:53 INFO - 'MOZ_NO_REMOTE': '1', 03:02:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:02:53 INFO - 'NO_EM_RESTART': '1', 03:02:53 INFO - 'PAGER': '/bin/cat', 03:02:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:53 INFO - 'PWD': '/builds/slave/test', 03:02:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:02:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:53 INFO - 'SHELL': '/bin/bash', 03:02:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IBN9tWPe7/Listeners', 03:02:53 INFO - 'TMPDIR': '/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/', 03:02:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:53 INFO - 'USER': 'cltbld', 03:02:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:53 INFO - 'XPC_FLAGS': '0x0', 03:02:53 INFO - 'XPC_SERVICE_NAME': '0', 03:02:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0282', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:02:53 INFO - mozversion application_buildid: 20160529004021 03:02:53 INFO - mozversion application_changeset: bfea62662298a8400d559abd40ec7d4d12fbd97d 03:02:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:02:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:02:53 INFO - mozversion application_name: Firefox 03:02:53 INFO - mozversion application_remotingname: firefox-dev 03:02:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:02:53 INFO - mozversion application_vendor: Mozilla 03:02:53 INFO - mozversion application_version: 48.0a2 03:02:53 INFO - mozversion platform_buildid: 20160529004021 03:02:53 INFO - mozversion platform_changeset: bfea62662298a8400d559abd40ec7d4d12fbd97d 03:02:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:02:53 INFO - mozversion platform_version: 48.0a2 03:02:53 INFO - using testdate: 1464516173 03:02:53 INFO - actual date: 1464516173 03:02:53 INFO - starting webserver on 'localhost:57082' 03:02:53 INFO - SUITE-START | Running 2 tests 03:02:53 INFO - TEST-START | dromaeo_css 03:02:53 INFO - operating with platform_type : mac_ 03:02:53 INFO - Initialising browser for dromaeo_css test... 03:02:53 INFO - TEST-INFO | started process 6318 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpVFuijj/profile http://localhost:57082/getInfo.html) 03:02:56 INFO - PROCESS | 6318 | __metrics Screen width/height:1600/1200 03:02:56 INFO - PROCESS | 6318 | colorDepth:24 03:02:56 INFO - PROCESS | 6318 | Browser inner width/height: 1024/689 03:02:56 INFO - PROCESS | 6318 | __metrics 03:02:56 INFO - PROCESS | 6318 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:02:56 ERROR - PROCESS | 6318 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:02:57 INFO - TEST-INFO | 6318: exit 0 03:02:57 INFO - Browser initialized. 03:02:57 INFO - Running cycle 1/1 for dromaeo_css test... 03:02:57 INFO - TEST-INFO | started process 6322 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpVFuijj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:03:09 INFO - PROCESS | 6322 | RSS: Main: 211251200 03:03:09 INFO - PROCESS | 6322 | 03:04:00 INFO - PROCESS | 6322 | 1464516240556 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:06:11 INFO - PROCESS | 6322 | Cycle 1(1): loaded http://localhost:57082/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57082/tests/dromaeo/cssquery-ext.html) 03:06:12 INFO - PROCESS | 6322 | RSS: Main: 351883264 03:06:12 INFO - PROCESS | 6322 | 03:09:15 INFO - PROCESS | 6322 | Cycle 1(1): loaded http://localhost:57082/tests/dromaeo/cssquery-ext.html (next: http://localhost:57082/tests/dromaeo/cssquery-jquery.html) 03:09:16 INFO - PROCESS | 6322 | RSS: Main: 357867520 03:09:16 INFO - PROCESS | 6322 | 03:12:18 INFO - PROCESS | 6322 | Cycle 1(1): loaded http://localhost:57082/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57082/tests/dromaeo/cssquery-mootools.html) 03:12:18 INFO - PROCESS | 6322 | RSS: Main: 361279488 03:12:18 INFO - PROCESS | 6322 | 03:15:21 INFO - PROCESS | 6322 | Cycle 1(1): loaded http://localhost:57082/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57082/tests/dromaeo/cssquery-prototype.html) 03:15:21 INFO - PROCESS | 6322 | RSS: Main: 360374272 03:15:21 INFO - PROCESS | 6322 | 03:18:25 INFO - PROCESS | 6322 | Cycle 1(1): loaded http://localhost:57082/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57082/tests/dromaeo/cssquery-yui.html) 03:18:26 INFO - PROCESS | 6322 | RSS: Main: 325545984 03:18:26 INFO - PROCESS | 6322 | 03:21:28 INFO - PROCESS | 6322 | Cycle 1(1): loaded http://localhost:57082/tests/dromaeo/cssquery-yui.html (next: http://localhost:57082/tests/dromaeo/cssquery-dojo.html) 03:21:28 INFO - PROCESS | 6322 | RSS: Main: 365068288 03:21:28 INFO - PROCESS | 6322 | 03:21:28 INFO - PROCESS | 6322 | __start_tp_report 03:21:28 INFO - PROCESS | 6322 | _x_x_mozilla_page_load 03:21:28 INFO - PROCESS | 6322 | _x_x_mozilla_page_load_details 03:21:28 INFO - PROCESS | 6322 | |i|pagename|runs| 03:21:28 INFO - PROCESS | 6322 | |0;dojo.html;3351.1119553318376;3418.247985443202;3418.572678415198;3448.9137771555725;3489.02307353941;5786.913196302054;5789.768409263625;5791.189233507307;5792.073268277071;5801.622894511854;5763.942360576393;5790.247267855173;5805.82582522525;5822.4177582241755;5832.241808564881;5133.050385678662;5139.15203991341;5185.351831021122;5236.31927849379;5333.519983201511;5200.765965531541;5204.479552044803;5213.713245771481;5215.660982036175;5219.164933610623;5158.174692049273;5203.609729270301;5212.452692467293;5235.581153507729;5242;5188.066148093341;5242.9213561796605;5246.895062098737;5257.553107985826;5307;5106.846794596168;5130.256112863651;5199.402068762065;5205.8958820823755;5208.218767184915;5437.075697131497;5534.1975413565215;5587.357453892813;5608.719564021823;5634.605577609569;6147.170132032164;6171.444569988723;6172.07418887166;6181;6190.380961903818;5753.654780713129;6116.235470566179;6212.65830379329;6219;6228.750849965996;5729.598928075036;5763.337216220147;5791;5794.21778059965;5829.067349224104;6241.063840423973;6259.217597800275;6274.247090349096;6288.52836037299;6292;5951.791687290924;6157.84605384869;6180.196574445384;6181.721822517908;6196.287426945959;6135.110179431545;6173.290071641729;6184.165137706442;6200.286966998763;6219.284782250099;6104.603200792023;6129.756146212483;6177.7837775677635;6202.627842329474;6217.098520714471;5982.282126144891;6044.214252147192;6044.486218671462;6047.78832740852;6072;4088.979555102265;4799.952000479951;4811.879703007453;4825.517448255217;4845.951540484621;4378.737275763465;4404.647628189737;4448.043670610834;4466.486354069259;4520.638348932078;4994.350734404504;5466.9179962300595;5674.971625141848;5694.715264236772;5705.942940570541;6070.484008859208;6282.183316168869;6282.968585157045;6327.145835312266;6330.715117819709;5578.804741834017;5588.91616625751;5595.496405323541;5600.775968961196;5611.915821262684;5337.81317653888;5344.038073146793;5345.064613692588;5348.1442969124755;5355.169948657886;6267.4986001120715;6301.369863013662;6310.085037669604;6324.778632747832;6327.145835312174;6251.093591429309;6267.749290028348;6298.338674439301;6299.559030867978;6320.051992201206;6291.653959032297;6305.464035557029;6320.083587879823;6320.115183874169;6336.493080553637;5199.246109314204;6049.637021778707;6137.285055221507;6140.197513349842;6230.314665386713;6110.175126357884;6283.120363149254;6298.0867774171575;6303.684815759286;6320.715567799368;6115.938840611537;6127.84680382994;6135.386461353829;6140.355262697352;6150.938490615036;5965.28416590012;6069.514438844793;6076.513878889767;6086.360932102066;6104.053871649901;4963.528464795839;5910.645361278165;5995.868343165966;6013.5189184865985;6016.097585362056;6119.632822030692;6129.35641757609;6130.570860039933;6143.938560614337;6148.754049838135;5844.824655260349;5959.7020148991505;5983.491403230774;6030.246219222598;6044.969775151272 03:21:28 INFO - PROCESS | 6322 | |1;ext.html;479.1662507237406;510.1124044163157;519.7323378460094;529.3066083430706;559.122178180257;30712;31408.68591314086;31428.52857207144;37312;38647;41168.58831411685;41634.791826040906;41675.95810104749;41706;41719;9470.81058378832;9678.612855485771;9682.951585242065;9693.22454203662;9698.466584337859;7365.815854603624;8814.206721395074;9469.10043545862;11439.142064345166;11677.182597218198;11479.713007174803;11544.134189935789;11546.711332216677;11558.306501609888;11570.305781653085;8586.914130858673;9612.375195612298;11447.828282575769;11449.198556101077;11452.427378631077;9271.536423178813;11743.94128029363;11753.000994915443;11766.58816941403;11771.646850594496;38282.425763613566;38692.41961370582;38849;38858.02854928621;38885;3204.6154461464776;3219.9839000804845;3253.1704415374165;3282.655321191264;3297.169646467912;27559.586606200923;28083.87664493418;28136.874525018975;28489.430211395655;29223.831046758318;2759.724027597224;2782.7773778097703;2782.9443411131665;2811.4517669054544;2850.059480371493;355229;355487;355575;438386;463947;36355.63644363522;64153;64363.356366436674;64503.709925801224;64682.0297695535;35911;35921;36005.27989440249;36066.63933360685;36079.81960090183;15730.606734831492;15890.364385424684;15912.363505459884;15921.363145474052;15935.12356820386;12472.189307695153;12681.112322137362;13370.26463544495;13464.461421543225;13477.19136851792;7536.811579710441;8200.835983280302;8288.378371622153;8353.707620233263;8469.3647976402;2915.883364665432;2969.346743716379;3408.9829550852087;3410.812405317731;3446.138465383654;890.6838072484304;1005.9990309641953;1035.2804800663466;1036.9429681367596;1045.9592705258317;22236.221732239617;29279.85360073186;29329.560056599166;30101;30843.691563084532;1148.26511032939;1181;1195.9940200299118;1205.1323047405854;1220.7062799771295;15361;15407.38370465169;15509.342947844809;15568.143752093743;15726.764098538531;605777;607369;617397;617819;618055;61768;61851;61901.07148392778;62076.68961655253;62215;1429.0639631041477;1518.6810769738258;1532.6015236038488;1544.0349781386633;1551.9068855868684;28161.859190703915;28797.712022879503;28924.843006279512;29289;29330.70669293365;47290;47671.52328476671;47914.760426197645;47996;48233.5176648229;335197;337333;337463;337684;339703;23885.402864928517;26908.59637105406;27345;27392.726072739813;27625.894964202012;43907.78046109749;44696.10607787891;45042.77478612586;46494.535054650376;46616;36049;36365.81817090898;37289.44065839069;37353.6264637361;38150.04624884289;36440.90677279686;36514.26971460609;36614.81692591627;37076.629233708394;37403.81298093492;36674.63325366819;36940.26119477542;37725;37853.43219851759;37957;32251.677483224867;32837.83581082079;33164.1708957278;33167.66832331646;33360.1659958503;23859.522809543363;24267.514649706554;24758.752412475646;24779.56613016124;24880.626790597795 03:21:28 INFO - PROCESS | 6322 | |2;jquery.html;371.8954704527554;391.85730441856333;393.50418472724357;396.5538768885004;409.1632611309871;18936.71594926077;19165.808341916578;19936.700949485752;19965.301214457497;20635.38093857183;19071.61856762864;19805.702914456295;20432;20808.271710490135;21551.892240538815;5768.509676677477;5808.4481974212595;6064.72708728106;6102.11519329697;6122.204113465244;5966.970165149168;6048.697565121748;6077.848053798646;6083.969580152093;6086.087086936973;5570.108782594785;5914.9408505915035;6024.728887200086;6030.276366835986;6047.183630209931;5627.212190293362;5848.122781582775;5860;5878.206442130322;5920.851978700523;5810.128480727924;5830.7959221427045;5863.179154918314;5866.677332746697;5894.97052514739;11960.880391196064;12171.0871684624;12741.936290318581;12904.612861614167;13403.932980335134;2125.4898824282213;2278.9544209115725;2363.83453158279;2632.907848225303;2701.149138021517;9226.446413215228;9319.534023298807;9736.172425343855;12551.93724031374;14380.908104757424;1721.4749501402068;1742.3466200174935;1777.3246166456663;1797.4068557376036;1868.1033104109968;101737.4913125437;109448;110590.89409105967;111515.99999999919;115999;9745.025497450199;11349;11632.941835290769;11699;11734.70663233426;15062.924685376503;15094.792416606864;15143.469978550698;15144.772828407582;15151.924240378728;7301.963490182621;8990.505522196212;8995.820083598292;9107.453552786854;9116.954415227881;7669.846603067907;8598.613062412207;8897.866532002025;8901.243394311427;9004.684836030707;4559.612432943237;4641.883952901205;4656.720596764205;4700.2009658358165;4723.173444647173;1711.3325802936865;1876.6622008038685;1938.1278424708937;1963.8625296229413;1986.4437957371958;915.8580420034905;948.393028461785;974.4250891973769;1004.7437903334603;1008.3748076192808;19759.012049397472;20428.489287767923;20503.17987280492;20529.89735051315;20901.790982089984;902.6389444222232;927.420362273579;938.671464987249;962.41292811385;1038.0864838941684;10804.24370294072;10946.945265273622;10977.890221097847;10985.956334148243;11007.009369156654;611007;613799;631822;635771;637804;91596;102172.48913755384;119619.40190298992;123566.76433235733;128127.71872281344;1010.838265877456;1014.5028935821394;1028.7942411517727;1036.2021243642353;1036.8548403223556;18216.815906966003;18312.633747325246;18398.1720822566;18418.72371914449;18557.886526808434;37010.259794803416;52083.69790755262;53849.192262116885;53931.46068539422;54623.35730225639;68943.31056689369;74030;75350;77110.45779084499;78182.2181778175;8041.155678653658;8116.513009219466;8300.875486867822;8392.20274073962;8483.618237179218;8799.47203167812;11872.46573904159;12764.680882978;13079.346032698517;13244.139130956457;11157.72105697364;11267.436628168723;11341.979221870071;11422.543098276308;11471.311721296748;11131.220814543229;11143.33140011602;11286.604968826052;11390.145739069769;11578.710532236424;9710.591964165298;11003.009729124416;11229.606963756229;11643.650690479299;11683.065354771428;8271.255586997198;8339.041010283894;8389.790255243668;8597.785055373666;8755.693550725695;8261.83476330482;10283.640072597424;11364.374959377314;11655.708607284885;11782.234154779911 03:21:28 INFO - PROCESS | 6322 | |3;mootools.html;1375.277979060993;1435.8635929586699;1611.6937781821453;1791.2029147029568;1933.0721253798167;7875.054993400786;8499.40504164708;8692.434991725533;8693.130686931303;8701.73894783156;6886;8406.28546573542;8624.698135565257;8635.568221588926;8644.870326945102;2160.6542953127505;2227.0201111510964;2229.2309153342153;2353.952920941576;2450.4976479821685;2236.5974124657555;2389.2832150354825;2454.006127518358;2457.336519139831;2457.533068716947;2353.5881220786378;2423.0307876849315;2433.926982190528;2436.1473484280536;2444.6821913151266;2138.5829763196184;2400.5558971590226;2404.158544509425;2416.0094361311953;2416.1664225842055;2207.9116835326568;2403.158894386968;2403.735589085199;2474.9752502474926;2475.158446128307;8603.698870539563;8697.56512174395;8698.521581313089;8710.782230444227;8733.170348816851;5293.682379057269;5410.08028635133;5446.673199608036;5466.535344495723;5469.261649677283;3049.1767222849926;3427.7771944823508;3505.8948231553095;3555.4311310190246;3566.6076731559506;1011.1607365886297;1023.8259495885717;1031.6131450705984;1049.0768124050862;1058.4548957287004;11887.346195959219;11910;11945.940270298679;11957.760844783055;11980.10149238802;4023.3562629979065;4284.914301713949;4669.836555720533;4753;4829.010052939139;10066.295359324777;10071.295009349275;10088.848667269996;10092.04125608066;10096.798064038678;5511.614187006871;5518.006758783377;5519.337679478488;5520.282363292747;5566.693831839207;4413.13943780966;4531.77341132942;5024.145895197825;5034.697918124924;5038.420581633086;3357.824761333514;3759.1166075972124;3829.9808500957315;3875.7868317242824;4077.062275676611;1333.081495914266;1333.280028784465;1335.732853429318;1336.3785839584639;1343.2142196814777;835.8495470815311;859.4628357276702;908.2007833106956;920.1212841736126;929.0430856218106;5574.581906356958;5692.516136128393;5725.770969161187;5779.971100144472;5877.089051197071;924.2051835421532;963.0706368354571;966.4829316315737;973.8003709239589;1004.3622299839508;3661.231141460323;3772.586866806738;3803.372443546784;3840.1743625120735;3842.634949679776;10330.741731456774;10642.787144257072;10673.626423075155;10813.405262710627;10814;10006.299559030798;10260.538275777608;10286.125679317192;10414.687559373231;10644.254902156774;8178.263956243966;8232;8319.209675080858;8329.416940814206;8344.41589088758;5278.366596008504;5483.725813709379;5558.471945165203;5692.971535142297;5786.276715410574;9143.405678630868;9342.579583918605;9475.47884866339;9483.525823708926;9483.857742134012;9146.725598231798;9501.714948551555;9604;9606.039396060338;9720.708378748648;7458.403327733659;7480.812979675552;7590.848183036419;7602.049743782028;7611.733589324347;6604.900926486011;7421.0723659542555;7519.248075192656;7885.723999659985;8214.83570328602;6806.7957961261245;7312.122545294599;7415.555066696015;7446.813829654086;7468.066491688539;6788.932110679028;7201.055714957037;7214.422846172189;7282.599457029914;7349.742759003409;6665.866682666223;6761.087253220752;6832.453403727789;6833.316668333326;6849.828754280983;4637.791299391603;4705.247160454311;4801.807927682993;4802.255650374198;4828.565429111397;4693.225617773166;4694.577488026094;4836.274558816206;4840.685355451885;4975.452700202903 03:21:28 INFO - PROCESS | 6322 | |4;prototype.html;307.93687294104706;316.2946629017291;321.14094796419573;321.47278463320146;328.3449518211169;6572.73709051638;6618.371254730799;6691.297413771551;6702.96648516758;6704.430123439513;5693.544516438686;6580.67096645167;6581.638009909453;6591.209054913405;6611.074449577051;4576.107659006503;4708.176069187895;4719.622430205575;4801.591864691505;4831;4135.441715368432;4751.16854550454;4812.783424745895;4812.879678008043;4823.469418363977;4748.35897153885;4769.713817170964;4771.308160316752;4773.737444440554;4785.401824771903;4561.29299958507;4676.0180362124165;4676.064787042571;4679.532046795327;4742.525747425264;3948.4077388391684;4046.898827529306;4059.6143366380443;4062.5328087270045;4075.449814275094;6204.100405441186;6225.346338634424;6241.687915604201;6343.333949935237;6366;5691.715414229313;5715.2855893013375;5717;5733.483986441241;5739.770409183628;6674.933250667528;6785.355391237825;7000.109970308039;7026.859462810767;7030.753923612649;4589.5639914208095;4621.491635920046;4625.213713668684;4626.1210370029585;4635.76821158944;6286.9056964145575;7029.92970070303;7397.556146631166;7408.444366672521;7416.7774966751085;4638.721676699409;4683.836065737751;4684.8360307389075;4690.64820138491;4708.317293992421;4749.002709430989;4756.762161891892;4759.857204283877;4761.714297142183;4769.070031343855;5628.986782379129;5647.068233741386;5647.181158732043;5647.265855438849;5647.632903861236;6112.899678057904;6702.497312701566;6718.798436046926;7060.470464715167;7064.788056358318;1594.4260066376096;1619.6841615885025;1627.5849658337286;1636.3045705575082;1641.9753703694455;989.9257555683208;1014.5992333028413;1032.6488993742014;1035.9067683908484;1039.163473403902;6362.0774987627465;6509.934900650933;6583.506237032242;6604.5046621502615;6641.402273795382;6966.233714291525;7174.529221509578;7402;7433;7448.776536703907;6677.465802735769;6692.130023096966;6692.564983276072;6693.163354580677;6693.464522838161;3863.2273545291055;3906.121122747359;3908.843646254175;3912.1002169500607;3919.725619206685;6223.533235007392;7622;7624.695012199561;7701.3068823806125;7714.112877019103;7509.474336796481;7531.246875312535;7548.584827834412;7553.1313898901235;7571.242875712494;6542.920418131051;7271.418286537064;7730.845383092306;7764.301212890867;7765.456418050908;7400.111986561432;7400.851982960418;7401.925980740339;7411.703531858665;7418.109826820816;6109.69451527413;6929.480288978448;7308.488405811542;7333.046703928455;7335.963320183365;7709.575973321521;7753.883691744515;7757.806054848448;7766.301032907066;7782.961085194538;4567.314902764612;4616.722996620214;4617.492075871584;4637.46669133054;4639.466461356988;7325.120985481776;7342.77971660851;7369.484136110421;7388.778336649909;7414.184439711736;5754.395788442321;6887.6556172192195;7110.644467776487;7122.7507037253445;7164.9641751793;5808.767649293981;6380;6612.80969425508;7200.063991681048;7236.529625574321;6971.51199416036;6973.6164510952385;6975.197852247056;6992.0910281663055;7021.964890175516;7155.642217889189;7161.39128174111;7166.856662866818;7173.24680908518;7178.318059784312;6659.300773418721;6672.266050734318;6845.828854278683;6962.965185174042;6970.965145174242 03:21:28 INFO - PROCESS | 6322 | |5;yui.html;700.2227527444537;736.3078706016345;746.7311767763607;753.4424525850872;797.7327595255591;13736.107153035045;13884.583462496115;13917.37371818268;13922.791158132612;13942.999999999987;13622.455101795917;13628.659283517916;13659.795103073437;13668.179909205455;13676.384562694679;716.7312257903286;733.2557454184008;898.5552151684925;922.0180507759216;922.0594993107027;907.5008745190152;924.856647219682;925.1396201532572;926.1664501948233;926.2266007883426;973.1679414100948;981.715302562256;985.4776968206826;986.5363279258772;986.6250824686609;768.3891306411389;960.3133759362055;985.1232403161192;987.7481242283244;988.9752756181081;732.3445516262915;732.8973943647893;733.1422235983912;733.2740586819064;733.6368497593672;14393.568192954228;14442.855571444255;14466.132032078109;14474.638134046627;14482;12281.201721888048;12457.252564846138;12559.120861539695;12635.052371072206;12753.61739147827;10136.847947280796;10155.187584993182;10269.89730102697;10322.071013608811;10441.216908731876;3428.9657103429145;3456.913577160566;3517.9296414071573;3531.470279458076;3548.254866478043;78869.60565197194;79716;80530.5973470129;83764;83862.74205886855;9364.719058428258;9479.24166066713;9530;9575.281853860988;9758.658446954323;20846;21089.15643374248;21095;21113.26103586367;21195.576088478454;7610.048743907012;7655.0813902332075;7800.258975397442;7887.684492620346;8012.0786109596975;4150.294449943517;4161.791910404468;4217.536071032184;4275.764832934156;4441.289393697058;3513.9297214056087;3583.7849729016343;3597.5143355647174;3598.946015809765;3600.963990360115;640.5708175522411;645.502962718704;654.4110300729382;661.8246164766341;664.6610228783259;566.3883006353128;592.863641362489;595.6009473652663;598.5451057196562;632.2855173653742;7922.524648521107;8088.312493438124;8139.470934389365;8145.470544414594;8296.875546866802;513.8663947373635;526.4603781124301;527.5647590737657;588.5997521685296;607.7994261893652;3698.4637227602384;3759.4548790425233;3794.013556475281;3803.980980095137;3820;90117.54941225384;90633.54683226542;91948;92086.07913920775;92670.53664731633;68587.65706171536;68934;70983;71250;72717.6364118176;66311.01066967999;66466;67558.31104222336;68502.999999999;68611.62776744437;8287.668493260202;8333.499990000619;8407;8425.873611895704;8535.914640853513;36540;44786;45081;45731;48048.759756201;61545.692271538355;69187.30812691808;70082.29917700758;70543.29456705572;70772.12001371178;11106;11136.276142050741;11383.975442210241;11426;11578.189526733047;8597.570121493774;11934.164608477324;12381.876181238073;12417.937910310391;12430.254184748945;9605.13553780163;10705.518251678848;10920.126389888632;10939.726506837074;11802.881971180179;11157;11324.6036388726;11464.025557827677;11528.077753779846;11879.930806227481;10969.50637221343;11079.83380249313;11349.659510214708;12032.518699252281;12134.817977730518;9365.11031452011;9460.693557965358;9763.463009534544;9792.314537982273;10126.139278161145;8627.482351058705;8836.293096552388;9661.661841835503;9716.611335546499;9878.654247101316 03:21:28 INFO - PROCESS | 6322 | __end_tp_report 03:21:28 INFO - PROCESS | 6322 | __start_cc_report 03:21:28 INFO - PROCESS | 6322 | _x_x_mozilla_cycle_collect,1360 03:21:28 INFO - PROCESS | 6322 | __end_cc_report 03:21:28 INFO - PROCESS | 6322 | __startTimestamp1464517288914__endTimestamp 03:21:28 INFO - PROCESS | 6322 | 03:21:28 INFO - PROCESS | 6322 | ------- Summary: start ------- 03:21:28 INFO - PROCESS | 6322 | Number of tests: 6 03:21:28 INFO - PROCESS | 6322 | 03:21:28 INFO - PROCESS | 6322 | [#0] dojo.html Cycles:165 Average:5712.34 Median:5965.28 stddev:644.55 (10.8%) stddev-sans-first:619.34 03:21:28 INFO - PROCESS | 6322 | Values: 3351.1 3418.2 3418.6 3448.9 3489.0 5786.9 5789.8 5791.2 5792.1 5801.6 5763.9 5790.2 5805.8 5822.4 5832.2 5133.1 5139.2 5185.4 5236.3 5333.5 5200.8 5204.5 5213.7 5215.7 5219.2 5158.2 5203.6 5212.5 5235.6 5242.0 5188.1 5242.9 5246.9 5257.6 5307.0 5106.8 5130.3 5199.4 5205.9 5208.2 5437.1 5534.2 5587.4 5608.7 5634.6 6147.2 6171.4 6172.1 6181.0 6190.4 5753.7 6116.2 6212.7 6219.0 6228.8 5729.6 5763.3 5791.0 5794.2 5829.1 6241.1 6259.2 6274.2 6288.5 6292.0 5951.8 6157.8 6180.2 6181.7 6196.3 6135.1 6173.3 6184.2 6200.3 6219.3 6104.6 6129.8 6177.8 6202.6 6217.1 5982.3 6044.2 6044.5 6047.8 6072.0 4089.0 4800.0 4811.9 4825.5 4846.0 4378.7 4404.6 4448.0 4466.5 4520.6 4994.4 5466.9 5675.0 5694.7 5705.9 6070.5 6282.2 6283.0 6327.1 6330.7 5578.8 5588.9 5595.5 5600.8 5611.9 5337.8 5344.0 5345.1 5348.1 5355.2 6267.5 6301.4 6310.1 6324.8 6327.1 6251.1 6267.7 6298.3 6299.6 6320.1 6291.7 6305.5 6320.1 6320.1 6336.5 5199.2 6049.6 6137.3 6140.2 6230.3 6110.2 6283.1 6298.1 6303.7 6320.7 6115.9 6127.8 6135.4 6140.4 6150.9 5965.3 6069.5 6076.5 6086.4 6104.1 4963.5 5910.6 5995.9 6013.5 6016.1 6119.6 6129.4 6130.6 6143.9 6148.8 5844.8 5959.7 5983.5 6030.2 6045.0 03:21:28 INFO - PROCESS | 6322 | 03:21:28 INFO - PROCESS | 6322 | [#1] ext.html Cycles:180 Average:58567.53 Median:337573.50 stddev:124127.97 (36.8%) stddev-sans-first:124399.56 03:21:28 INFO - PROCESS | 6322 | Values: 479.2 510.1 519.7 529.3 559.1 30712.0 31408.7 31428.5 37312.0 38647.0 41168.6 41634.8 41676.0 41706.0 41719.0 9470.8 9678.6 9683.0 9693.2 9698.5 7365.8 8814.2 9469.1 11439.1 11677.2 11479.7 11544.1 11546.7 11558.3 11570.3 8586.9 9612.4 11447.8 11449.2 11452.4 9271.5 11743.9 11753.0 11766.6 11771.6 38282.4 38692.4 38849.0 38858.0 38885.0 3204.6 3220.0 3253.2 3282.7 3297.2 27559.6 28083.9 28136.9 28489.4 29223.8 2759.7 2782.8 2782.9 2811.5 2850.1 355229.0 355487.0 355575.0 438386.0 463947.0 36355.6 64153.0 64363.4 64503.7 64682.0 35911.0 35921.0 36005.3 36066.6 36079.8 15730.6 15890.4 15912.4 15921.4 15935.1 12472.2 12681.1 13370.3 13464.5 13477.2 7536.8 8200.8 8288.4 8353.7 8469.4 2915.9 2969.3 3409.0 3410.8 3446.1 890.7 1006.0 1035.3 1036.9 1046.0 22236.2 29279.9 29329.6 30101.0 30843.7 1148.3 1181.0 1196.0 1205.1 1220.7 15361.0 15407.4 15509.3 15568.1 15726.8 605777.0 607369.0 617397.0 617819.0 618055.0 61768.0 61851.0 61901.1 62076.7 62215.0 1429.1 1518.7 1532.6 1544.0 1551.9 28161.9 28797.7 28924.8 29289.0 29330.7 47290.0 47671.5 47914.8 47996.0 48233.5 335197.0 337333.0 337463.0 337684.0 339703.0 23885.4 26908.6 27345.0 27392.7 27625.9 43907.8 44696.1 45042.8 46494.5 46616.0 36049.0 36365.8 37289.4 37353.6 38150.0 36440.9 36514.3 36614.8 37076.6 37403.8 36674.6 36940.3 37725.0 37853.4 37957.0 32251.7 32837.8 33164.2 33167.7 33360.2 23859.5 24267.5 24758.8 24779.6 24880.6 03:21:28 INFO - PROCESS | 6322 | 03:21:28 INFO - PROCESS | 6322 | [#2] jquery.html Cycles:180 Average:34663.01 Median:11915.42 stddev:103768.17 (870.9%) stddev-sans-first:104027.32 03:21:28 INFO - PROCESS | 6322 | Values: 371.9 391.9 393.5 396.6 409.2 18936.7 19165.8 19936.7 19965.3 20635.4 19071.6 19805.7 20432.0 20808.3 21551.9 5768.5 5808.4 6064.7 6102.1 6122.2 5967.0 6048.7 6077.8 6084.0 6086.1 5570.1 5914.9 6024.7 6030.3 6047.2 5627.2 5848.1 5860.0 5878.2 5920.9 5810.1 5830.8 5863.2 5866.7 5895.0 11960.9 12171.1 12741.9 12904.6 13403.9 2125.5 2279.0 2363.8 2632.9 2701.1 9226.4 9319.5 9736.2 12551.9 14380.9 1721.5 1742.3 1777.3 1797.4 1868.1 101737.5 109448.0 110590.9 111516.0 115999.0 9745.0 11349.0 11632.9 11699.0 11734.7 15062.9 15094.8 15143.5 15144.8 15151.9 7302.0 8990.5 8995.8 9107.5 9117.0 7669.8 8598.6 8897.9 8901.2 9004.7 4559.6 4641.9 4656.7 4700.2 4723.2 1711.3 1876.7 1938.1 1963.9 1986.4 915.9 948.4 974.4 1004.7 1008.4 19759.0 20428.5 20503.2 20529.9 20901.8 902.6 927.4 938.7 962.4 1038.1 10804.2 10946.9 10977.9 10986.0 11007.0 611007.0 613799.0 631822.0 635771.0 637804.0 91596.0 102172.5 119619.4 123566.8 128127.7 1010.8 1014.5 1028.8 1036.2 1036.9 18216.8 18312.6 18398.2 18418.7 18557.9 37010.3 52083.7 53849.2 53931.5 54623.4 68943.3 74030.0 75350.0 77110.5 78182.2 8041.2 8116.5 8300.9 8392.2 8483.6 8799.5 11872.5 12764.7 13079.3 13244.1 11157.7 11267.4 11342.0 11422.5 11471.3 11131.2 11143.3 11286.6 11390.1 11578.7 9710.6 11003.0 11229.6 11643.7 11683.1 8271.3 8339.0 8389.8 8597.8 8755.7 8261.8 10283.6 11364.4 11655.7 11782.2 03:21:28 INFO - PROCESS | 6322 | 03:21:28 INFO - PROCESS | 6322 | [#3] mootools.html Cycles:180 Average:5603.70 Median:4838.48 stddev:3130.19 (64.7%) stddev-sans-first:3122.84 03:21:28 INFO - PROCESS | 6322 | Values: 1375.3 1435.9 1611.7 1791.2 1933.1 7875.1 8499.4 8692.4 8693.1 8701.7 6886.0 8406.3 8624.7 8635.6 8644.9 2160.7 2227.0 2229.2 2354.0 2450.5 2236.6 2389.3 2454.0 2457.3 2457.5 2353.6 2423.0 2433.9 2436.1 2444.7 2138.6 2400.6 2404.2 2416.0 2416.2 2207.9 2403.2 2403.7 2475.0 2475.2 8603.7 8697.6 8698.5 8710.8 8733.2 5293.7 5410.1 5446.7 5466.5 5469.3 3049.2 3427.8 3505.9 3555.4 3566.6 1011.2 1023.8 1031.6 1049.1 1058.5 11887.3 11910.0 11945.9 11957.8 11980.1 4023.4 4284.9 4669.8 4753.0 4829.0 10066.3 10071.3 10088.8 10092.0 10096.8 5511.6 5518.0 5519.3 5520.3 5566.7 4413.1 4531.8 5024.1 5034.7 5038.4 3357.8 3759.1 3830.0 3875.8 4077.1 1333.1 1333.3 1335.7 1336.4 1343.2 835.8 859.5 908.2 920.1 929.0 5574.6 5692.5 5725.8 5780.0 5877.1 924.2 963.1 966.5 973.8 1004.4 3661.2 3772.6 3803.4 3840.2 3842.6 10330.7 10642.8 10673.6 10813.4 10814.0 10006.3 10260.5 10286.1 10414.7 10644.3 8178.3 8232.0 8319.2 8329.4 8344.4 5278.4 5483.7 5558.5 5693.0 5786.3 9143.4 9342.6 9475.5 9483.5 9483.9 9146.7 9501.7 9604.0 9606.0 9720.7 7458.4 7480.8 7590.8 7602.0 7611.7 6604.9 7421.1 7519.2 7885.7 8214.8 6806.8 7312.1 7415.6 7446.8 7468.1 6788.9 7201.1 7214.4 7282.6 7349.7 6665.9 6761.1 6832.5 6833.3 6849.8 4637.8 4705.2 4801.8 4802.3 4828.6 4693.2 4694.6 4836.3 4840.7 4975.5 03:21:28 INFO - PROCESS | 6322 | 03:21:28 INFO - PROCESS | 6322 | [#4] prototype.html Cycles:180 Average:5741.09 Median:6576.70 stddev:1845.86 (28.1%) stddev-sans-first:1805.43 03:21:28 INFO - PROCESS | 6322 | Values: 307.9 316.3 321.1 321.5 328.3 6572.7 6618.4 6691.3 6703.0 6704.4 5693.5 6580.7 6581.6 6591.2 6611.1 4576.1 4708.2 4719.6 4801.6 4831.0 4135.4 4751.2 4812.8 4812.9 4823.5 4748.4 4769.7 4771.3 4773.7 4785.4 4561.3 4676.0 4676.1 4679.5 4742.5 3948.4 4046.9 4059.6 4062.5 4075.4 6204.1 6225.3 6241.7 6343.3 6366.0 5691.7 5715.3 5717.0 5733.5 5739.8 6674.9 6785.4 7000.1 7026.9 7030.8 4589.6 4621.5 4625.2 4626.1 4635.8 6286.9 7029.9 7397.6 7408.4 7416.8 4638.7 4683.8 4684.8 4690.6 4708.3 4749.0 4756.8 4759.9 4761.7 4769.1 5629.0 5647.1 5647.2 5647.3 5647.6 6112.9 6702.5 6718.8 7060.5 7064.8 1594.4 1619.7 1627.6 1636.3 1642.0 989.9 1014.6 1032.6 1035.9 1039.2 6362.1 6509.9 6583.5 6604.5 6641.4 6966.2 7174.5 7402.0 7433.0 7448.8 6677.5 6692.1 6692.6 6693.2 6693.5 3863.2 3906.1 3908.8 3912.1 3919.7 6223.5 7622.0 7624.7 7701.3 7714.1 7509.5 7531.2 7548.6 7553.1 7571.2 6542.9 7271.4 7730.8 7764.3 7765.5 7400.1 7400.9 7401.9 7411.7 7418.1 6109.7 6929.5 7308.5 7333.0 7336.0 7709.6 7753.9 7757.8 7766.3 7783.0 4567.3 4616.7 4617.5 4637.5 4639.5 7325.1 7342.8 7369.5 7388.8 7414.2 5754.4 6887.7 7110.6 7122.8 7165.0 5808.8 6380.0 6612.8 7200.1 7236.5 6971.5 6973.6 6975.2 6992.1 7022.0 7155.6 7161.4 7166.9 7173.2 7178.3 6659.3 6672.3 6845.8 6963.0 6971.0 03:21:28 INFO - PROCESS | 6322 | 03:21:28 INFO - PROCESS | 6322 | [#5] yui.html Cycles:180 Average:17798.90 Median:643.04 stddev:24923.35 (3875.9%) stddev-sans-first:24960.20 03:21:28 INFO - PROCESS | 6322 | Values: 700.2 736.3 746.7 753.4 797.7 13736.1 13884.6 13917.4 13922.8 13943.0 13622.5 13628.7 13659.8 13668.2 13676.4 716.7 733.3 898.6 922.0 922.1 907.5 924.9 925.1 926.2 926.2 973.2 981.7 985.5 986.5 986.6 768.4 960.3 985.1 987.7 989.0 732.3 732.9 733.1 733.3 733.6 14393.6 14442.9 14466.1 14474.6 14482.0 12281.2 12457.3 12559.1 12635.1 12753.6 10136.8 10155.2 10269.9 10322.1 10441.2 3429.0 3456.9 3517.9 3531.5 3548.3 78869.6 79716.0 80530.6 83764.0 83862.7 9364.7 9479.2 9530.0 9575.3 9758.7 20846.0 21089.2 21095.0 21113.3 21195.6 7610.0 7655.1 7800.3 7887.7 8012.1 4150.3 4161.8 4217.5 4275.8 4441.3 3513.9 3583.8 3597.5 3598.9 3601.0 640.6 645.5 654.4 661.8 664.7 566.4 592.9 595.6 598.5 632.3 7922.5 8088.3 8139.5 8145.5 8296.9 513.9 526.5 527.6 588.6 607.8 3698.5 3759.5 3794.0 3804.0 3820.0 90117.5 90633.5 91948.0 92086.1 92670.5 68587.7 68934.0 70983.0 71250.0 72717.6 66311.0 66466.0 67558.3 68503.0 68611.6 8287.7 8333.5 8407.0 8425.9 8535.9 36540.0 44786.0 45081.0 45731.0 48048.8 61545.7 69187.3 70082.3 70543.3 70772.1 11106.0 11136.3 11384.0 11426.0 11578.2 8597.6 11934.2 12381.9 12417.9 12430.3 9605.1 10705.5 10920.1 10939.7 11802.9 11157.0 11324.6 11464.0 11528.1 11879.9 10969.5 11079.8 11349.7 12032.5 12134.8 9365.1 9460.7 9763.5 9792.3 10126.1 8627.5 8836.3 9661.7 9716.6 9878.7 03:21:28 INFO - PROCESS | 6322 | -------- Summary: end -------- 03:21:28 INFO - PROCESS | 6322 | 03:21:29 INFO - TEST-INFO | 6322: exit 0 03:21:29 INFO - TEST-OK | dromaeo_css | took 1115932ms 03:21:29 INFO - TEST-START | kraken 03:21:29 INFO - operating with platform_type : mac_ 03:21:29 INFO - Initialising browser for kraken test... 03:21:29 INFO - TEST-INFO | started process 6324 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpGGVulG/profile http://localhost:57082/getInfo.html) 03:21:31 INFO - PROCESS | 6324 | __metrics Screen width/height:1600/1200 03:21:31 INFO - PROCESS | 6324 | colorDepth:24 03:21:31 INFO - PROCESS | 6324 | Browser inner width/height: 1024/689 03:21:31 INFO - PROCESS | 6324 | __metrics 03:21:31 INFO - PROCESS | 6324 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:21:31 ERROR - PROCESS | 6324 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:21:32 INFO - TEST-INFO | 6324: exit 0 03:21:32 INFO - Browser initialized. 03:21:32 INFO - Running cycle 1/1 for kraken test... 03:21:32 INFO - TEST-INFO | started process 6327 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/tmpGGVulG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:21:44 INFO - PROCESS | 6327 | RSS: Main: 211333120 03:21:44 INFO - PROCESS | 6327 | 03:21:47 INFO - PROCESS | 6327 | Cycle 1(1): loaded http://localhost:57082/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57082/tests/kraken/driver.html?testName=audio-beat-detection) 03:21:48 INFO - PROCESS | 6327 | RSS: Main: 271605760 03:21:48 INFO - PROCESS | 6327 | 03:21:50 INFO - PROCESS | 6327 | Cycle 1(1): loaded http://localhost:57082/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57082/tests/kraken/driver.html?testName=audio-dft) 03:21:51 INFO - PROCESS | 6327 | RSS: Main: 285839360 03:21:51 INFO - PROCESS | 6327 | 03:21:54 INFO - PROCESS | 6327 | Cycle 1(1): loaded http://localhost:57082/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57082/tests/kraken/driver.html?testName=audio-fft) 03:21:55 INFO - PROCESS | 6327 | RSS: Main: 372297728 03:21:55 INFO - PROCESS | 6327 | 03:21:57 INFO - PROCESS | 6327 | Cycle 1(1): loaded http://localhost:57082/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57082/tests/kraken/driver.html?testName=audio-oscillator) 03:21:57 INFO - PROCESS | 6327 | RSS: Main: 277934080 03:21:57 INFO - PROCESS | 6327 | 03:22:00 INFO - PROCESS | 6327 | Cycle 1(1): loaded http://localhost:57082/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57082/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:22:00 INFO - PROCESS | 6327 | RSS: Main: 278925312 03:22:00 INFO - PROCESS | 6327 | 03:22:03 INFO - PROCESS | 6327 | Cycle 1(1): loaded http://localhost:57082/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57082/tests/kraken/driver.html?testName=imaging-darkroom) 03:22:04 INFO - PROCESS | 6327 | RSS: Main: 317980672 03:22:04 INFO - PROCESS | 6327 | 03:22:07 INFO - PROCESS | 6327 | Cycle 1(1): loaded http://localhost:57082/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57082/tests/kraken/driver.html?testName=imaging-desaturate) 03:22:07 INFO - PROCESS | 6327 | RSS: Main: 319373312 03:22:07 INFO - PROCESS | 6327 | 03:22:10 INFO - PROCESS | 6327 | Cycle 1(1): loaded http://localhost:57082/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57082/tests/kraken/driver.html?testName=json-parse-financial) 03:22:11 INFO - PROCESS | 6327 | RSS: Main: 317562880 03:22:11 INFO - PROCESS | 6327 | 03:22:12 INFO - PROCESS | 6327 | Cycle 1(1): loaded http://localhost:57082/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57082/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:22:13 INFO - PROCESS | 6327 | RSS: Main: 309125120 03:22:13 INFO - PROCESS | 6327 | 03:22:15 INFO - PROCESS | 6327 | Cycle 1(1): loaded http://localhost:57082/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57082/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:22:15 INFO - PROCESS | 6327 | RSS: Main: 315498496 03:22:15 INFO - PROCESS | 6327 | 03:22:18 INFO - PROCESS | 6327 | Cycle 1(1): loaded http://localhost:57082/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57082/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:22:18 INFO - PROCESS | 6327 | RSS: Main: 318464000 03:22:18 INFO - PROCESS | 6327 | 03:22:21 INFO - PROCESS | 6327 | Cycle 1(1): loaded http://localhost:57082/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57082/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:22:22 INFO - PROCESS | 6327 | RSS: Main: 323846144 03:22:22 INFO - PROCESS | 6327 | 03:22:24 INFO - PROCESS | 6327 | Cycle 1(1): loaded http://localhost:57082/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57082/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:22:25 INFO - PROCESS | 6327 | RSS: Main: 322023424 03:22:25 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | Cycle 1(1): loaded http://localhost:57082/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57082/tests/kraken/driver.html?testName=ai-astar) 03:22:27 INFO - PROCESS | 6327 | RSS: Main: 386904064 03:22:27 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | __start_tp_report 03:22:27 INFO - PROCESS | 6327 | _x_x_mozilla_page_load 03:22:27 INFO - PROCESS | 6327 | _x_x_mozilla_page_load_details 03:22:27 INFO - PROCESS | 6327 | |i|pagename|runs| 03:22:27 INFO - PROCESS | 6327 | |0;ai-astar;114;116;121;115;112;113;114;115;114;134 03:22:27 INFO - PROCESS | 6327 | |1;audio-beat-detection;168;149;182;167;118;144;121;155;159;120 03:22:27 INFO - PROCESS | 6327 | |2;audio-dft;184;185;191;184;185;187;182;185;186;185 03:22:27 INFO - PROCESS | 6327 | |3;audio-fft;71;79;77;155;57;56;60;65;129;55 03:22:27 INFO - PROCESS | 6327 | |4;audio-oscillator;98;93;142;78;82;95;163;114;110;127 03:22:27 INFO - PROCESS | 6327 | |5;imaging-gaussian-blur;88;84;87;85;87;84;88;84;87;84 03:22:27 INFO - PROCESS | 6327 | |6;imaging-darkroom;93;93;93;93;103;145;111;93;93;92 03:22:27 INFO - PROCESS | 6327 | |7;imaging-desaturate;85;83;88;86;84;83;84;83;84;84 03:22:27 INFO - PROCESS | 6327 | |8;json-parse-financial;60;63;63;64;69;64;64;87;61;60 03:22:27 INFO - PROCESS | 6327 | |9;json-stringify-tinderbox;65;65;74;80;71;73;69;71;70;70 03:22:27 INFO - PROCESS | 6327 | |10;stanford-crypto-aes;81;79;94;102;107;80;77;81;82;85 03:22:27 INFO - PROCESS | 6327 | |11;stanford-crypto-ccm;124;128;130;129;126;131;127;131;148;134 03:22:27 INFO - PROCESS | 6327 | |12;stanford-crypto-pbkdf2;167;165;169;165;166;167;177;170;170;215 03:22:27 INFO - PROCESS | 6327 | |13;stanford-crypto-sha256-iterative;68;69;69;67;69;74;92;91;66;57 03:22:27 INFO - PROCESS | 6327 | __end_tp_report 03:22:27 INFO - PROCESS | 6327 | __start_cc_report 03:22:27 INFO - PROCESS | 6327 | _x_x_mozilla_cycle_collect,2028 03:22:27 INFO - PROCESS | 6327 | __end_cc_report 03:22:27 INFO - PROCESS | 6327 | __startTimestamp1464517347667__endTimestamp 03:22:27 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | ------- Summary: start ------- 03:22:27 INFO - PROCESS | 6327 | Number of tests: 14 03:22:27 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | [#0] ai-astar Cycles:10 Average:116.80 Median:115.00 stddev:6.51 (5.7%) stddev-sans-first:6.83 03:22:27 INFO - PROCESS | 6327 | Values: 114.0 116.0 121.0 115.0 112.0 113.0 114.0 115.0 114.0 134.0 03:22:27 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | [#1] audio-beat-detection Cycles:10 Average:148.30 Median:157.00 stddev:22.40 (14.3%) stddev-sans-first:22.60 03:22:27 INFO - PROCESS | 6327 | Values: 168.0 149.0 182.0 167.0 118.0 144.0 121.0 155.0 159.0 120.0 03:22:27 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | [#2] audio-dft Cycles:10 Average:185.40 Median:185.00 stddev:2.37 (1.3%) stddev-sans-first:2.46 03:22:27 INFO - PROCESS | 6327 | Values: 184.0 185.0 191.0 184.0 185.0 187.0 182.0 185.0 186.0 185.0 03:22:27 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | [#3] audio-fft Cycles:10 Average:80.40 Median:62.50 stddev:34.11 (54.6%) stddev-sans-first:36.01 03:22:27 INFO - PROCESS | 6327 | Values: 71.0 79.0 77.0 155.0 57.0 56.0 60.0 65.0 129.0 55.0 03:22:27 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | [#4] audio-oscillator Cycles:10 Average:110.20 Median:80.00 stddev:27.09 (33.9%) stddev-sans-first:28.37 03:22:27 INFO - PROCESS | 6327 | Values: 98.0 93.0 142.0 78.0 82.0 95.0 163.0 114.0 110.0 127.0 03:22:27 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:1.75 (2.0%) stddev-sans-first:1.67 03:22:27 INFO - PROCESS | 6327 | Values: 88.0 84.0 87.0 85.0 87.0 84.0 88.0 84.0 87.0 84.0 03:22:27 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | [#6] imaging-darkroom Cycles:10 Average:100.90 Median:93.00 stddev:16.68 (17.9%) stddev-sans-first:17.45 03:22:27 INFO - PROCESS | 6327 | Values: 93.0 93.0 93.0 93.0 103.0 145.0 111.0 93.0 93.0 92.0 03:22:27 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.00 stddev:1.58 (1.9%) stddev-sans-first:1.66 03:22:27 INFO - PROCESS | 6327 | Values: 85.0 83.0 88.0 86.0 84.0 83.0 84.0 83.0 84.0 84.0 03:22:27 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:64.00 stddev:7.99 (12.5%) stddev-sans-first:8.22 03:22:27 INFO - PROCESS | 6327 | Values: 60.0 63.0 63.0 64.0 69.0 64.0 64.0 87.0 61.0 60.0 03:22:27 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.00 stddev:4.37 (6.2%) stddev-sans-first:4.10 03:22:27 INFO - PROCESS | 6327 | Values: 65.0 65.0 74.0 80.0 71.0 73.0 69.0 71.0 70.0 70.0 03:22:27 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:81.00 stddev:10.48 (12.9%) stddev-sans-first:10.90 03:22:27 INFO - PROCESS | 6327 | Values: 81.0 79.0 94.0 102.0 107.0 80.0 77.0 81.0 82.0 85.0 03:22:27 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | [#11] stanford-crypto-ccm Cycles:10 Average:130.80 Median:130.50 stddev:6.68 (5.1%) stddev-sans-first:6.62 03:22:27 INFO - PROCESS | 6327 | Values: 124.0 128.0 130.0 129.0 126.0 131.0 127.0 131.0 148.0 134.0 03:22:27 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:169.50 stddev:15.14 (8.9%) stddev-sans-first:15.90 03:22:27 INFO - PROCESS | 6327 | Values: 167.0 165.0 169.0 165.0 166.0 167.0 177.0 170.0 170.0 215.0 03:22:27 INFO - PROCESS | 6327 | 03:22:27 INFO - PROCESS | 6327 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:69.00 stddev:11.02 (16.0%) stddev-sans-first:11.59 03:22:27 INFO - PROCESS | 6327 | Values: 68.0 69.0 69.0 67.0 69.0 74.0 92.0 91.0 66.0 57.0 03:22:27 INFO - PROCESS | 6327 | -------- Summary: end -------- 03:22:27 INFO - PROCESS | 6327 | 03:22:28 INFO - TEST-INFO | 6327: exit 0 03:22:28 INFO - TEST-OK | kraken | took 59017ms 03:22:28 INFO - SUITE-END | took 1174s 03:22:28 INFO - Completed test suite (00:19:35) 03:22:28 INFO - javascript benchmark 03:22:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3351.1119553318376, 3418.247985443202, 3418.572678415198, 3448.9137771555725, 3489.02307353941, 5786.913196302054, 5789.768409263625, 5791.189233507307, 5792.073268277071, 5801.622894511854, 5763.942360576393, 5790.247267855173, 5805.82582522525, 5822.4177582241755, 5832.241808564881, 5133.050385678662, 5139.15203991341, 5185.351831021122, 5236.31927849379, 5333.519983201511, 5200.765965531541, 5204.479552044803, 5213.713245771481, 5215.660982036175, 5219.164933610623, 5158.174692049273, 5203.609729270301, 5212.452692467293, 5235.581153507729, 5242.0, 5188.066148093341, 5242.9213561796605, 5246.895062098737, 5257.553107985826, 5307.0, 5106.846794596168, 5130.256112863651, 5199.402068762065, 5205.8958820823755, 5208.218767184915, 5437.075697131497, 5534.1975413565215, 5587.357453892813, 5608.719564021823, 5634.605577609569, 6147.170132032164, 6171.444569988723, 6172.07418887166, 6181.0, 6190.380961903818, 5753.654780713129, 6116.235470566179, 6212.65830379329, 6219.0, 6228.750849965996, 5729.598928075036, 5763.337216220147, 5791.0, 5794.21778059965, 5829.067349224104, 6241.063840423973, 6259.217597800275, 6274.247090349096, 6288.52836037299, 6292.0, 5951.791687290924, 6157.84605384869, 6180.196574445384, 6181.721822517908, 6196.287426945959, 6135.110179431545, 6173.290071641729, 6184.165137706442, 6200.286966998763, 6219.284782250099, 6104.603200792023, 6129.756146212483, 6177.7837775677635, 6202.627842329474, 6217.098520714471, 5982.282126144891, 6044.214252147192, 6044.486218671462, 6047.78832740852, 6072.0, 4088.979555102265, 4799.952000479951, 4811.879703007453, 4825.517448255217, 4845.951540484621, 4378.737275763465, 4404.647628189737, 4448.043670610834, 4466.486354069259, 4520.638348932078, 4994.350734404504, 5466.9179962300595, 5674.971625141848, 5694.715264236772, 5705.942940570541, 6070.484008859208, 6282.183316168869, 6282.968585157045, 6327.145835312266, 6330.715117819709, 5578.804741834017, 5588.91616625751, 5595.496405323541, 5600.775968961196, 5611.915821262684, 5337.81317653888, 5344.038073146793, 5345.064613692588, 5348.1442969124755, 5355.169948657886, 6267.4986001120715, 6301.369863013662, 6310.085037669604, 6324.778632747832, 6327.145835312174, 6251.093591429309, 6267.749290028348, 6298.338674439301, 6299.559030867978, 6320.051992201206, 6291.653959032297, 6305.464035557029, 6320.083587879823, 6320.115183874169, 6336.493080553637, 5199.246109314204, 6049.637021778707, 6137.285055221507, 6140.197513349842, 6230.314665386713, 6110.175126357884, 6283.120363149254, 6298.0867774171575, 6303.684815759286, 6320.715567799368, 6115.938840611537, 6127.84680382994, 6135.386461353829, 6140.355262697352, 6150.938490615036, 5965.28416590012, 6069.514438844793, 6076.513878889767, 6086.360932102066, 6104.053871649901, 4963.528464795839, 5910.645361278165, 5995.868343165966, 6013.5189184865985, 6016.097585362056, 6119.632822030692, 6129.35641757609, 6130.570860039933, 6143.938560614337, 6148.754049838135, 5844.824655260349, 5959.7020148991505, 5983.491403230774, 6030.246219222598, 6044.969775151272], "value": 5671.620084294347, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.1662507237406, 510.1124044163157, 519.7323378460094, 529.3066083430706, 559.122178180257, 30712.0, 31408.68591314086, 31428.52857207144, 37312.0, 38647.0, 41168.58831411685, 41634.791826040906, 41675.95810104749, 41706.0, 41719.0, 9470.81058378832, 9678.612855485771, 9682.951585242065, 9693.22454203662, 9698.466584337859, 7365.815854603624, 8814.206721395074, 9469.10043545862, 11439.142064345166, 11677.182597218198, 11479.713007174803, 11544.134189935789, 11546.711332216677, 11558.306501609888, 11570.305781653085, 8586.914130858673, 9612.375195612298, 11447.828282575769, 11449.198556101077, 11452.427378631077, 9271.536423178813, 11743.94128029363, 11753.000994915443, 11766.58816941403, 11771.646850594496, 38282.425763613566, 38692.41961370582, 38849.0, 38858.02854928621, 38885.0, 3204.6154461464776, 3219.9839000804845, 3253.1704415374165, 3282.655321191264, 3297.169646467912, 27559.586606200923, 28083.87664493418, 28136.874525018975, 28489.430211395655, 29223.831046758318, 2759.724027597224, 2782.7773778097703, 2782.9443411131665, 2811.4517669054544, 2850.059480371493, 355229.0, 355487.0, 355575.0, 438386.0, 463947.0, 36355.63644363522, 64153.0, 64363.356366436674, 64503.709925801224, 64682.0297695535, 35911.0, 35921.0, 36005.27989440249, 36066.63933360685, 36079.81960090183, 15730.606734831492, 15890.364385424684, 15912.363505459884, 15921.363145474052, 15935.12356820386, 12472.189307695153, 12681.112322137362, 13370.26463544495, 13464.461421543225, 13477.19136851792, 7536.811579710441, 8200.835983280302, 8288.378371622153, 8353.707620233263, 8469.3647976402, 2915.883364665432, 2969.346743716379, 3408.9829550852087, 3410.812405317731, 3446.138465383654, 890.6838072484304, 1005.9990309641953, 1035.2804800663466, 1036.9429681367596, 1045.9592705258317, 22236.221732239617, 29279.85360073186, 29329.560056599166, 30101.0, 30843.691563084532, 1148.26511032939, 1181.0, 1195.9940200299118, 1205.1323047405854, 1220.7062799771295, 15361.0, 15407.38370465169, 15509.342947844809, 15568.143752093743, 15726.764098538531, 605777.0, 607369.0, 617397.0, 617819.0, 618055.0, 61768.0, 61851.0, 61901.07148392778, 62076.68961655253, 62215.0, 1429.0639631041477, 1518.6810769738258, 1532.6015236038488, 1544.0349781386633, 1551.9068855868684, 28161.859190703915, 28797.712022879503, 28924.843006279512, 29289.0, 29330.70669293365, 47290.0, 47671.52328476671, 47914.760426197645, 47996.0, 48233.5176648229, 335197.0, 337333.0, 337463.0, 337684.0, 339703.0, 23885.402864928517, 26908.59637105406, 27345.0, 27392.726072739813, 27625.894964202012, 43907.78046109749, 44696.10607787891, 45042.77478612586, 46494.535054650376, 46616.0, 36049.0, 36365.81817090898, 37289.44065839069, 37353.6264637361, 38150.04624884289, 36440.90677279686, 36514.26971460609, 36614.81692591627, 37076.629233708394, 37403.81298093492, 36674.63325366819, 36940.26119477542, 37725.0, 37853.43219851759, 37957.0, 32251.677483224867, 32837.83581082079, 33164.1708957278, 33167.66832331646, 33360.1659958503, 23859.522809543363, 24267.514649706554, 24758.752412475646, 24779.56613016124, 24880.626790597795], "value": 18338.077498033083, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.8954704527554, 391.85730441856333, 393.50418472724357, 396.5538768885004, 409.1632611309871, 18936.71594926077, 19165.808341916578, 19936.700949485752, 19965.301214457497, 20635.38093857183, 19071.61856762864, 19805.702914456295, 20432.0, 20808.271710490135, 21551.892240538815, 5768.509676677477, 5808.4481974212595, 6064.72708728106, 6102.11519329697, 6122.204113465244, 5966.970165149168, 6048.697565121748, 6077.848053798646, 6083.969580152093, 6086.087086936973, 5570.108782594785, 5914.9408505915035, 6024.728887200086, 6030.276366835986, 6047.183630209931, 5627.212190293362, 5848.122781582775, 5860.0, 5878.206442130322, 5920.851978700523, 5810.128480727924, 5830.7959221427045, 5863.179154918314, 5866.677332746697, 5894.97052514739, 11960.880391196064, 12171.0871684624, 12741.936290318581, 12904.612861614167, 13403.932980335134, 2125.4898824282213, 2278.9544209115725, 2363.83453158279, 2632.907848225303, 2701.149138021517, 9226.446413215228, 9319.534023298807, 9736.172425343855, 12551.93724031374, 14380.908104757424, 1721.4749501402068, 1742.3466200174935, 1777.3246166456663, 1797.4068557376036, 1868.1033104109968, 101737.4913125437, 109448.0, 110590.89409105967, 111515.99999999919, 115999.0, 9745.025497450199, 11349.0, 11632.941835290769, 11699.0, 11734.70663233426, 15062.924685376503, 15094.792416606864, 15143.469978550698, 15144.772828407582, 15151.924240378728, 7301.963490182621, 8990.505522196212, 8995.820083598292, 9107.453552786854, 9116.954415227881, 7669.846603067907, 8598.613062412207, 8897.866532002025, 8901.243394311427, 9004.684836030707, 4559.612432943237, 4641.883952901205, 4656.720596764205, 4700.2009658358165, 4723.173444647173, 1711.3325802936865, 1876.6622008038685, 1938.1278424708937, 1963.8625296229413, 1986.4437957371958, 915.8580420034905, 948.393028461785, 974.4250891973769, 1004.7437903334603, 1008.3748076192808, 19759.012049397472, 20428.489287767923, 20503.17987280492, 20529.89735051315, 20901.790982089984, 902.6389444222232, 927.420362273579, 938.671464987249, 962.41292811385, 1038.0864838941684, 10804.24370294072, 10946.945265273622, 10977.890221097847, 10985.956334148243, 11007.009369156654, 611007.0, 613799.0, 631822.0, 635771.0, 637804.0, 91596.0, 102172.48913755384, 119619.40190298992, 123566.76433235733, 128127.71872281344, 1010.838265877456, 1014.5028935821394, 1028.7942411517727, 1036.2021243642353, 1036.8548403223556, 18216.815906966003, 18312.633747325246, 18398.1720822566, 18418.72371914449, 18557.886526808434, 37010.259794803416, 52083.69790755262, 53849.192262116885, 53931.46068539422, 54623.35730225639, 68943.31056689369, 74030.0, 75350.0, 77110.45779084499, 78182.2181778175, 8041.155678653658, 8116.513009219466, 8300.875486867822, 8392.20274073962, 8483.618237179218, 8799.47203167812, 11872.46573904159, 12764.680882978, 13079.346032698517, 13244.139130956457, 11157.72105697364, 11267.436628168723, 11341.979221870071, 11422.543098276308, 11471.311721296748, 11131.220814543229, 11143.33140011602, 11286.604968826052, 11390.145739069769, 11578.710532236424, 9710.591964165298, 11003.009729124416, 11229.606963756229, 11643.650690479299, 11683.065354771428, 8271.255586997198, 8339.041010283894, 8389.790255243668, 8597.785055373666, 8755.693550725695, 8261.83476330482, 10283.640072597424, 11364.374959377314, 11655.708607284885, 11782.234154779911], "value": 9411.370383619664, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1375.277979060993, 1435.8635929586699, 1611.6937781821453, 1791.2029147029568, 1933.0721253798167, 7875.054993400786, 8499.40504164708, 8692.434991725533, 8693.130686931303, 8701.73894783156, 6886.0, 8406.28546573542, 8624.698135565257, 8635.568221588926, 8644.870326945102, 2160.6542953127505, 2227.0201111510964, 2229.2309153342153, 2353.952920941576, 2450.4976479821685, 2236.5974124657555, 2389.2832150354825, 2454.006127518358, 2457.336519139831, 2457.533068716947, 2353.5881220786378, 2423.0307876849315, 2433.926982190528, 2436.1473484280536, 2444.6821913151266, 2138.5829763196184, 2400.5558971590226, 2404.158544509425, 2416.0094361311953, 2416.1664225842055, 2207.9116835326568, 2403.158894386968, 2403.735589085199, 2474.9752502474926, 2475.158446128307, 8603.698870539563, 8697.56512174395, 8698.521581313089, 8710.782230444227, 8733.170348816851, 5293.682379057269, 5410.08028635133, 5446.673199608036, 5466.535344495723, 5469.261649677283, 3049.1767222849926, 3427.7771944823508, 3505.8948231553095, 3555.4311310190246, 3566.6076731559506, 1011.1607365886297, 1023.8259495885717, 1031.6131450705984, 1049.0768124050862, 1058.4548957287004, 11887.346195959219, 11910.0, 11945.940270298679, 11957.760844783055, 11980.10149238802, 4023.3562629979065, 4284.914301713949, 4669.836555720533, 4753.0, 4829.010052939139, 10066.295359324777, 10071.295009349275, 10088.848667269996, 10092.04125608066, 10096.798064038678, 5511.614187006871, 5518.006758783377, 5519.337679478488, 5520.282363292747, 5566.693831839207, 4413.13943780966, 4531.77341132942, 5024.145895197825, 5034.697918124924, 5038.420581633086, 3357.824761333514, 3759.1166075972124, 3829.9808500957315, 3875.7868317242824, 4077.062275676611, 1333.081495914266, 1333.280028784465, 1335.732853429318, 1336.3785839584639, 1343.2142196814777, 835.8495470815311, 859.4628357276702, 908.2007833106956, 920.1212841736126, 929.0430856218106, 5574.581906356958, 5692.516136128393, 5725.770969161187, 5779.971100144472, 5877.089051197071, 924.2051835421532, 963.0706368354571, 966.4829316315737, 973.8003709239589, 1004.3622299839508, 3661.231141460323, 3772.586866806738, 3803.372443546784, 3840.1743625120735, 3842.634949679776, 10330.741731456774, 10642.787144257072, 10673.626423075155, 10813.405262710627, 10814.0, 10006.299559030798, 10260.538275777608, 10286.125679317192, 10414.687559373231, 10644.254902156774, 8178.263956243966, 8232.0, 8319.209675080858, 8329.416940814206, 8344.41589088758, 5278.366596008504, 5483.725813709379, 5558.471945165203, 5692.971535142297, 5786.276715410574, 9143.405678630868, 9342.579583918605, 9475.47884866339, 9483.525823708926, 9483.857742134012, 9146.725598231798, 9501.714948551555, 9604.0, 9606.039396060338, 9720.708378748648, 7458.403327733659, 7480.812979675552, 7590.848183036419, 7602.049743782028, 7611.733589324347, 6604.900926486011, 7421.0723659542555, 7519.248075192656, 7885.723999659985, 8214.83570328602, 6806.7957961261245, 7312.122545294599, 7415.555066696015, 7446.813829654086, 7468.066491688539, 6788.932110679028, 7201.055714957037, 7214.422846172189, 7282.599457029914, 7349.742759003409, 6665.866682666223, 6761.087253220752, 6832.453403727789, 6833.316668333326, 6849.828754280983, 4637.791299391603, 4705.247160454311, 4801.807927682993, 4802.255650374198, 4828.565429111397, 4693.225617773166, 4694.577488026094, 4836.274558816206, 4840.685355451885, 4975.452700202903], "value": 4526.017122836351, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.93687294104706, 316.2946629017291, 321.14094796419573, 321.47278463320146, 328.3449518211169, 6572.73709051638, 6618.371254730799, 6691.297413771551, 6702.96648516758, 6704.430123439513, 5693.544516438686, 6580.67096645167, 6581.638009909453, 6591.209054913405, 6611.074449577051, 4576.107659006503, 4708.176069187895, 4719.622430205575, 4801.591864691505, 4831.0, 4135.441715368432, 4751.16854550454, 4812.783424745895, 4812.879678008043, 4823.469418363977, 4748.35897153885, 4769.713817170964, 4771.308160316752, 4773.737444440554, 4785.401824771903, 4561.29299958507, 4676.0180362124165, 4676.064787042571, 4679.532046795327, 4742.525747425264, 3948.4077388391684, 4046.898827529306, 4059.6143366380443, 4062.5328087270045, 4075.449814275094, 6204.100405441186, 6225.346338634424, 6241.687915604201, 6343.333949935237, 6366.0, 5691.715414229313, 5715.2855893013375, 5717.0, 5733.483986441241, 5739.770409183628, 6674.933250667528, 6785.355391237825, 7000.109970308039, 7026.859462810767, 7030.753923612649, 4589.5639914208095, 4621.491635920046, 4625.213713668684, 4626.1210370029585, 4635.76821158944, 6286.9056964145575, 7029.92970070303, 7397.556146631166, 7408.444366672521, 7416.7774966751085, 4638.721676699409, 4683.836065737751, 4684.8360307389075, 4690.64820138491, 4708.317293992421, 4749.002709430989, 4756.762161891892, 4759.857204283877, 4761.714297142183, 4769.070031343855, 5628.986782379129, 5647.068233741386, 5647.181158732043, 5647.265855438849, 5647.632903861236, 6112.899678057904, 6702.497312701566, 6718.798436046926, 7060.470464715167, 7064.788056358318, 1594.4260066376096, 1619.6841615885025, 1627.5849658337286, 1636.3045705575082, 1641.9753703694455, 989.9257555683208, 1014.5992333028413, 1032.6488993742014, 1035.9067683908484, 1039.163473403902, 6362.0774987627465, 6509.934900650933, 6583.506237032242, 6604.5046621502615, 6641.402273795382, 6966.233714291525, 7174.529221509578, 7402.0, 7433.0, 7448.776536703907, 6677.465802735769, 6692.130023096966, 6692.564983276072, 6693.163354580677, 6693.464522838161, 3863.2273545291055, 3906.121122747359, 3908.843646254175, 3912.1002169500607, 3919.725619206685, 6223.533235007392, 7622.0, 7624.695012199561, 7701.3068823806125, 7714.112877019103, 7509.474336796481, 7531.246875312535, 7548.584827834412, 7553.1313898901235, 7571.242875712494, 6542.920418131051, 7271.418286537064, 7730.845383092306, 7764.301212890867, 7765.456418050908, 7400.111986561432, 7400.851982960418, 7401.925980740339, 7411.703531858665, 7418.109826820816, 6109.69451527413, 6929.480288978448, 7308.488405811542, 7333.046703928455, 7335.963320183365, 7709.575973321521, 7753.883691744515, 7757.806054848448, 7766.301032907066, 7782.961085194538, 4567.314902764612, 4616.722996620214, 4617.492075871584, 4637.46669133054, 4639.466461356988, 7325.120985481776, 7342.77971660851, 7369.484136110421, 7388.778336649909, 7414.184439711736, 5754.395788442321, 6887.6556172192195, 7110.644467776487, 7122.7507037253445, 7164.9641751793, 5808.767649293981, 6380.0, 6612.80969425508, 7200.063991681048, 7236.529625574321, 6971.51199416036, 6973.6164510952385, 6975.197852247056, 6992.0910281663055, 7021.964890175516, 7155.642217889189, 7161.39128174111, 7166.856662866818, 7173.24680908518, 7178.318059784312, 6659.300773418721, 6672.266050734318, 6845.828854278683, 6962.965185174042, 6970.965145174242], "value": 5117.7448596706545, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.2227527444537, 736.3078706016345, 746.7311767763607, 753.4424525850872, 797.7327595255591, 13736.107153035045, 13884.583462496115, 13917.37371818268, 13922.791158132612, 13942.999999999987, 13622.455101795917, 13628.659283517916, 13659.795103073437, 13668.179909205455, 13676.384562694679, 716.7312257903286, 733.2557454184008, 898.5552151684925, 922.0180507759216, 922.0594993107027, 907.5008745190152, 924.856647219682, 925.1396201532572, 926.1664501948233, 926.2266007883426, 973.1679414100948, 981.715302562256, 985.4776968206826, 986.5363279258772, 986.6250824686609, 768.3891306411389, 960.3133759362055, 985.1232403161192, 987.7481242283244, 988.9752756181081, 732.3445516262915, 732.8973943647893, 733.1422235983912, 733.2740586819064, 733.6368497593672, 14393.568192954228, 14442.855571444255, 14466.132032078109, 14474.638134046627, 14482.0, 12281.201721888048, 12457.252564846138, 12559.120861539695, 12635.052371072206, 12753.61739147827, 10136.847947280796, 10155.187584993182, 10269.89730102697, 10322.071013608811, 10441.216908731876, 3428.9657103429145, 3456.913577160566, 3517.9296414071573, 3531.470279458076, 3548.254866478043, 78869.60565197194, 79716.0, 80530.5973470129, 83764.0, 83862.74205886855, 9364.719058428258, 9479.24166066713, 9530.0, 9575.281853860988, 9758.658446954323, 20846.0, 21089.15643374248, 21095.0, 21113.26103586367, 21195.576088478454, 7610.048743907012, 7655.0813902332075, 7800.258975397442, 7887.684492620346, 8012.0786109596975, 4150.294449943517, 4161.791910404468, 4217.536071032184, 4275.764832934156, 4441.289393697058, 3513.9297214056087, 3583.7849729016343, 3597.5143355647174, 3598.946015809765, 3600.963990360115, 640.5708175522411, 645.502962718704, 654.4110300729382, 661.8246164766341, 664.6610228783259, 566.3883006353128, 592.863641362489, 595.6009473652663, 598.5451057196562, 632.2855173653742, 7922.524648521107, 8088.312493438124, 8139.470934389365, 8145.470544414594, 8296.875546866802, 513.8663947373635, 526.4603781124301, 527.5647590737657, 588.5997521685296, 607.7994261893652, 3698.4637227602384, 3759.4548790425233, 3794.013556475281, 3803.980980095137, 3820.0, 90117.54941225384, 90633.54683226542, 91948.0, 92086.07913920775, 92670.53664731633, 68587.65706171536, 68934.0, 70983.0, 71250.0, 72717.6364118176, 66311.01066967999, 66466.0, 67558.31104222336, 68502.999999999, 68611.62776744437, 8287.668493260202, 8333.499990000619, 8407.0, 8425.873611895704, 8535.914640853513, 36540.0, 44786.0, 45081.0, 45731.0, 48048.759756201, 61545.692271538355, 69187.30812691808, 70082.29917700758, 70543.29456705572, 70772.12001371178, 11106.0, 11136.276142050741, 11383.975442210241, 11426.0, 11578.189526733047, 8597.570121493774, 11934.164608477324, 12381.876181238073, 12417.937910310391, 12430.254184748945, 9605.13553780163, 10705.518251678848, 10920.126389888632, 10939.726506837074, 11802.881971180179, 11157.0, 11324.6036388726, 11464.025557827677, 11528.077753779846, 11879.930806227481, 10969.50637221343, 11079.83380249313, 11349.659510214708, 12032.518699252281, 12134.817977730518, 9365.11031452011, 9460.693557965358, 9763.463009534544, 9792.314537982273, 10126.139278161145, 8627.482351058705, 8836.293096552388, 9661.661841835503, 9716.611335546499, 9878.654247101316], "value": 6840.84609718032, "unit": "score"}], "name": "dromaeo_css", "value": 7330.083829464913, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 121.0, 115.0, 112.0, 113.0, 114.0, 115.0, 114.0, 134.0], "value": 116.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 149.0, 182.0, 167.0, 118.0, 144.0, 121.0, 155.0, 159.0, 120.0], "value": 148.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 191.0, 184.0, 185.0, 187.0, 182.0, 185.0, 186.0, 185.0], "value": 185.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 79.0, 77.0, 155.0, 57.0, 56.0, 60.0, 65.0, 129.0, 55.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 93.0, 142.0, 78.0, 82.0, 95.0, 163.0, 114.0, 110.0, 127.0], "value": 110.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 85.0, 87.0, 84.0, 88.0, 84.0, 87.0, 84.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 103.0, 145.0, 111.0, 93.0, 93.0, 92.0], "value": 100.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 88.0, 86.0, 84.0, 83.0, 84.0, 83.0, 84.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 63.0, 64.0, 69.0, 64.0, 64.0, 87.0, 61.0, 60.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 74.0, 80.0, 71.0, 73.0, 69.0, 71.0, 70.0, 70.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 94.0, 102.0, 107.0, 80.0, 77.0, 81.0, 82.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 128.0, 130.0, 129.0, 126.0, 131.0, 127.0, 131.0, 148.0, 134.0], "value": 130.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 169.0, 165.0, 166.0, 167.0, 177.0, 170.0, 170.0, 215.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 67.0, 69.0, 74.0, 92.0, 91.0, 66.0, 57.0], "value": 72.2, "unit": "score"}], "name": "kraken", "value": 1511.3999999999999, "alertThreshold": 2.0}]} 03:22:28 INFO - Return code: 0 03:22:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:22:28 ERROR - # TBPL SUCCESS # 03:22:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:22:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:22:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:22:29 INFO - 2016-05-29 03:22:29.001 screenresolution[6332:108595] starting screenresolution argv=screenresolution get 03:22:29 INFO - 2016-05-29 03:22:29.004 screenresolution[6332:108595] Display 0: 1600x1200x32@60 03:22:29 INFO - 2016-05-29 03:22:29.010 screenresolution[6333:108597] starting screenresolution argv=screenresolution list 03:22:29 INFO - Available Modes on Display 0 03:22:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:22:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:22:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:22:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:22:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:22:29 INFO - Intel Iris: 03:22:29 INFO - Chipset Model: Intel Iris 03:22:29 INFO - Type: GPU 03:22:29 INFO - Bus: Built-In 03:22:29 INFO - VRAM (Dynamic, Max): 1536 MB 03:22:29 INFO - Vendor: Intel (0x8086) 03:22:29 INFO - Device ID: 0x0a2e 03:22:29 INFO - Revision ID: 0x0009 03:22:29 INFO - Displays: 03:22:29 INFO - Display: 03:22:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:22:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:22:29 INFO - Display Serial Number: 1600x1200 60 03:22:29 INFO - Main Display: Yes 03:22:29 INFO - Mirror: Off 03:22:29 INFO - Online: Yes 03:22:29 INFO - Rotation: Supported 03:22:29 INFO - Return code: 0 03:22:29 INFO - Running post-action listener: _resource_record_post_action 03:22:29 INFO - Running post-run listener: _resource_record_post_run 03:22:30 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 1621504; Write bytes: 655163904; Read time: 20; Write time: 1869 03:22:30 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 350759424; Write bytes: 346408960; Read time: 27203; Write time: 745 03:22:30 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 1596928; Write bytes: 297961984; Read time: 19; Write time: 1086 03:22:30 INFO - Running post-run listener: _upload_blobber_files 03:22:30 INFO - Blob upload gear active. 03:22:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:22:30 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:22:30 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:22:30 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:22:30 INFO - (blobuploader) - INFO - Open directory for files ... 03:22:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:22:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:22:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:22:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:22:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:22:31 INFO - (blobuploader) - INFO - Done attempting. 03:22:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:22:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:22:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:22:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:22:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:22:32 INFO - (blobuploader) - INFO - Done attempting. 03:22:32 INFO - (blobuploader) - INFO - Iteration through files over. 03:22:32 INFO - Return code: 0 03:22:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:22:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:22:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f426389f3728eb180ed6a8b458535397962f90404a28c462990e5672790bbf15504fcd896ba6ad39d3e7a1a6e3b7dfada34b27f087211a8d7b663b4c93ede53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f318c5f5eb7e9ece14a4e32eb48d06355974a8d00ab9827951d83c8e8bdab036e9f8942b80ba4fdaeed1e7826b1704ab63f08218893d3d8166b4b9a65f7179d4"} 03:22:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:22:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:22:32 INFO - Contents: 03:22:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f426389f3728eb180ed6a8b458535397962f90404a28c462990e5672790bbf15504fcd896ba6ad39d3e7a1a6e3b7dfada34b27f087211a8d7b663b4c93ede53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f318c5f5eb7e9ece14a4e32eb48d06355974a8d00ab9827951d83c8e8bdab036e9f8942b80ba4fdaeed1e7826b1704ab63f08218893d3d8166b4b9a65f7179d4"} 03:22:32 INFO - Running post-run listener: copy_logs_to_upload_dir 03:22:32 INFO - Copying logs to upload dir... 03:22:32 INFO - mkdir: /builds/slave/test/build/upload/logs 03:22:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1332.812288 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 12 secs) (at 2016-05-29 03:22:33.070503) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-29 03:22:33.073840) ========= 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.rJuH0u2Cti/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.5IBN9tWPe7/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f426389f3728eb180ed6a8b458535397962f90404a28c462990e5672790bbf15504fcd896ba6ad39d3e7a1a6e3b7dfada34b27f087211a8d7b663b4c93ede53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f318c5f5eb7e9ece14a4e32eb48d06355974a8d00ab9827951d83c8e8bdab036e9f8942b80ba4fdaeed1e7826b1704ab63f08218893d3d8166b4b9a65f7179d4"} build_url:https://queue.taskcluster.net/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011475 build_url: 'https://queue.taskcluster.net/v1/task/Z-1h9aHaSg2BLSUeVxK9zA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f426389f3728eb180ed6a8b458535397962f90404a28c462990e5672790bbf15504fcd896ba6ad39d3e7a1a6e3b7dfada34b27f087211a8d7b663b4c93ede53", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f318c5f5eb7e9ece14a4e32eb48d06355974a8d00ab9827951d83c8e8bdab036e9f8942b80ba4fdaeed1e7826b1704ab63f08218893d3d8166b4b9a65f7179d4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-29 03:22:33.107956) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 03:22:33.108281) ========= 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.rJuH0u2Cti/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.5IBN9tWPe7/Listeners TMPDIR=/var/folders/h3/20tcmn5d6x595zch842vg_xh00000w/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.005342 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 03:22:33.157446) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-29 03:22:33.157760) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-29 03:22:33.158056) ========= ========= Total master_lag: 0.19 =========