builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0127 starttime: 1461419258.01 results: success (0) buildid: 20160423050055 builduid: f2d1db1a8bd44c43bed859d4fa648c51 revision: 2be765b599b2d90103013526ef8ebe684b66b218 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-23 06:47:38.006342) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-23 06:47:38.006844) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-23 06:47:38.007179) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-23 06:47:38.050320) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 06:47:38.050716) ========= 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.kZmTHcAoWZ/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.inGUcsUWGh/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-23 06:47:38-- 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% 16.1M=0.001s 2016-04-23 06:47:38 (16.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.617398 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 06:47:38.689444) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 06:47:38.690174) ========= 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.kZmTHcAoWZ/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.inGUcsUWGh/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.019719 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 06:47:38.743214) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 06:47:38.744025) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2be765b599b2d90103013526ef8ebe684b66b218 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 2be765b599b2d90103013526ef8ebe684b66b218 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kZmTHcAoWZ/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.inGUcsUWGh/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-23 06:47:38,817 truncating revision to first 12 chars 2016-04-23 06:47:38,817 Setting DEBUG logging. 2016-04-23 06:47:38,817 attempt 1/10 2016-04-23 06:47:38,817 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2be765b599b2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-23 06:47:39,395 unpacking tar archive at: mozilla-central-2be765b599b2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.831308 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 06:47:39.593024) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-23 06:47:39.593345) ========= script_repo_revision: 2be765b599b2d90103013526ef8ebe684b66b218 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-23 06:47:39.593789) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-23 06:47:39.594127) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-23 06:47:39.607656) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 2 secs) (at 2016-04-23 06:47:39.608082) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kZmTHcAoWZ/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.inGUcsUWGh/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:47:39 INFO - MultiFileLogger online at 20160423 06:47:39 in /builds/slave/test 06:47:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:47:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:47:39 INFO - {'append_to_log': False, 06:47:39 INFO - 'base_work_dir': '/builds/slave/test', 06:47:39 INFO - 'blob_upload_branch': 'Firefox', 06:47:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:47:39 INFO - 'branch': 'Firefox', 06:47:39 INFO - 'buildbot_json_path': 'buildprops.json', 06:47:39 INFO - 'config_files': ('talos/mac_config.py',), 06:47:39 INFO - 'default_actions': ('clobber', 06:47:39 INFO - 'read-buildbot-config', 06:47:39 INFO - 'download-and-extract', 06:47:39 INFO - 'populate-webroot', 06:47:39 INFO - 'create-virtualenv', 06:47:39 INFO - 'install', 06:47:39 INFO - 'run-tests'), 06:47:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:47:39 INFO - 'download_minidump_stackwalk': True, 06:47:39 INFO - 'download_symbols': 'ondemand', 06:47:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:47:39 INFO - 'tooltool.py': '/tools/tooltool.py', 06:47:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:47:39 INFO - '/tools/misc-python/virtualenv.py')}, 06:47:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:47:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:47:39 INFO - 'installer_path': 'installer.exe', 06:47:39 INFO - 'log_level': 'info', 06:47:39 INFO - 'log_name': 'talos', 06:47:39 INFO - 'log_to_console': True, 06:47:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:47:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:47:39 INFO - 'opt_config_files': (), 06:47:39 INFO - 'pip_index': False, 06:47:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:47:39 INFO - 'cmd': ('bash', 06:47:39 INFO - '-c', 06:47:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:47:39 INFO - 'enabled': True, 06:47:39 INFO - 'halt_on_failure': False, 06:47:39 INFO - 'name': 'check_screen_resolution'},), 06:47:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:47:39 INFO - 'cmd': ('bash', 06:47:39 INFO - '-c', 06:47:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:47:39 INFO - 'enabled': True, 06:47:39 INFO - 'halt_on_failure': False, 06:47:39 INFO - 'name': 'check_screen_resolution'},), 06:47:39 INFO - 'run_cmd_checks_enabled': True, 06:47:39 INFO - 'sps_profile': False, 06:47:39 INFO - 'sps_profile_interval': 0, 06:47:39 INFO - 'suite': 'dromaeojs', 06:47:39 INFO - 'system_bits': '32', 06:47:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:47:39 INFO - 'title': 't-yosemite-r7-0127', 06:47:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:47:39 INFO - 'use_talos_json': True, 06:47:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:47:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:47:39 INFO - 'work_dir': 'build'} 06:47:39 INFO - ##### 06:47:39 INFO - ##### Running clobber step. 06:47:39 INFO - ##### 06:47:39 INFO - Running pre-action listener: _resource_record_pre_action 06:47:39 INFO - Running main action method: clobber 06:47:39 INFO - rmtree: /builds/slave/test/build 06:47:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:47:41 INFO - Running post-action listener: _resource_record_post_action 06:47:41 INFO - ##### 06:47:41 INFO - ##### Running read-buildbot-config step. 06:47:41 INFO - ##### 06:47:41 INFO - Running pre-action listener: _resource_record_pre_action 06:47:41 INFO - Running main action method: read_buildbot_config 06:47:41 INFO - Using buildbot properties: 06:47:41 INFO - { 06:47:41 INFO - "project": "", 06:47:41 INFO - "product": "firefox", 06:47:41 INFO - "script_repo_revision": "production", 06:47:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 06:47:41 INFO - "repository": "", 06:47:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 06:47:41 INFO - "buildid": "20160423050055", 06:47:41 INFO - "pgo_build": "False", 06:47:41 INFO - "basedir": "/builds/slave/test", 06:47:41 INFO - "buildnumber": 228, 06:47:41 INFO - "slavename": "t-yosemite-r7-0127", 06:47:41 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 06:47:41 INFO - "platform": "yosemite_r7", 06:47:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:47:41 INFO - "branch": "mozilla-central", 06:47:41 INFO - "repo_path": "mozilla-central", 06:47:41 INFO - "revision": "2be765b599b2d90103013526ef8ebe684b66b218", 06:47:41 INFO - "stage_platform": "macosx64", 06:47:41 INFO - "builduid": "f2d1db1a8bd44c43bed859d4fa648c51", 06:47:41 INFO - "slavebuilddir": "test" 06:47:41 INFO - } 06:47:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 06:47:41 INFO - Running post-action listener: _resource_record_post_action 06:47:41 INFO - ##### 06:47:41 INFO - ##### Running download-and-extract step. 06:47:41 INFO - ##### 06:47:41 INFO - Running pre-action listener: _resource_record_pre_action 06:47:41 INFO - Running main action method: download_and_extract 06:47:41 INFO - mkdir: /builds/slave/test/build/tests 06:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:41 INFO - https://queue.taskcluster.net/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:47:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 06:47:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 06:47:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 06:47:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 06:48:11 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json: timed out 06:48:11 INFO - retry: attempt #1 caught exception: timed out 06:48:11 INFO - retry: Failed, sleeping 30 seconds before retrying 06:48:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #2 06:48:48 INFO - Downloaded 1199 bytes. 06:48:48 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 06:48:48 INFO - Using the following test package requirements: 06:48:48 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 06:48:48 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 06:48:48 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 06:48:48 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 06:48:48 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 06:48:48 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 06:48:48 INFO - u'jsshell-mac.zip'], 06:48:48 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 06:48:48 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 06:48:48 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 06:48:48 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 06:48:48 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 06:48:48 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 06:48:48 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 06:48:48 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 06:48:48 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 06:48:48 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 06:48:48 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 06:48:48 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:48:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:48 INFO - https://queue.taskcluster.net/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:48:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 06:48:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 06:48:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 06:48:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:48:51 INFO - Downloaded 18109542 bytes. 06:48:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:48:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:48:54 INFO - Return code: 0 06:48:54 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:48: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')]} 06:48:54 INFO - https://queue.taskcluster.net/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:48:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 06:48:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 06:48:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 06:48:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:48:57 INFO - Downloaded 18109542 bytes. 06:48:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:48:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:49:00 INFO - Return code: 0 06:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:49:00 INFO - https://queue.taskcluster.net/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:49:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 06:49:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 06:49:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 06:49:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:49:01 INFO - Downloaded 10840145 bytes. 06:49:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:49:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 06:49:01 INFO - Return code: 0 06:49: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')]} 06:49:01 INFO - https://queue.taskcluster.net/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 06:49:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 06:49:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 06:49:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 06:49:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 06:49:05 INFO - Downloaded 110121298 bytes. 06:49:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 06:49:05 INFO - mkdir: /builds/slave/test/properties 06:49:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:49:05 INFO - Writing to file /builds/slave/test/properties/build_url 06:49:05 INFO - Contents: 06:49:05 INFO - build_url:https://queue.taskcluster.net/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 06:49:07 INFO - Running post-action listener: _resource_record_post_action 06:49:07 INFO - Running post-action listener: set_extra_try_arguments 06:49:07 INFO - ##### 06:49:07 INFO - ##### Running populate-webroot step. 06:49:07 INFO - ##### 06:49:07 INFO - Running pre-action listener: _resource_record_pre_action 06:49:07 INFO - Running main action method: populate_webroot 06:49:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:49:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:49:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:49:07 INFO - '--tppagecycles', 06:49:07 INFO - '7'], 06:49:07 INFO - 'tests': ['tsvgm']}}, 06:49:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:49:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:49:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:49:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:49:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:49:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:49:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:49:07 INFO - 'talos_options': ['--disable-e10s'], 06:49:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:49:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:49:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:49:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:49:07 INFO - 'talos_options': ['--disable-e10s'], 06:49:07 INFO - 'tests': ['damp', 'tps']}, 06:49:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:49:07 INFO - 'tests': ['damp', 'tps']}, 06:49:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:49:07 INFO - 'tests': ['dromaeo_dom']}, 06:49:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:49:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:49:07 INFO - 'tests': ['a11yr', 06:49:07 INFO - 'ts_paint', 06:49:07 INFO - 'tpaint', 06:49:07 INFO - 'sessionrestore', 06:49:07 INFO - 'sessionrestore_no_auto_restore', 06:49:07 INFO - 'tabpaint']}, 06:49:07 INFO - 'other-e10s': {'tests': ['a11yr', 06:49:07 INFO - 'ts_paint', 06:49:07 INFO - 'tpaint', 06:49:07 INFO - 'sessionrestore', 06:49:07 INFO - 'sessionrestore_no_auto_restore', 06:49:07 INFO - 'tabpaint']}, 06:49:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:49:07 INFO - 'ts_paint', 06:49:07 INFO - 'tpaint', 06:49:07 INFO - 'sessionrestore', 06:49:07 INFO - 'sessionrestore_no_auto_restore', 06:49:07 INFO - 'tabpaint']}, 06:49:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:49:07 INFO - 'ts_paint', 06:49:07 INFO - 'tpaint', 06:49:07 INFO - 'sessionrestore', 06:49:07 INFO - 'sessionrestore_no_auto_restore', 06:49:07 INFO - 'tabpaint']}, 06:49:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:49:07 INFO - 'tests': ['a11yr', 06:49:07 INFO - 'ts_paint', 06:49:07 INFO - 'tpaint', 06:49:07 INFO - 'sessionrestore', 06:49:07 INFO - 'sessionrestore_no_auto_restore', 06:49:07 INFO - 'tabpaint']}, 06:49:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:49:07 INFO - 'tests': ['a11yr', 06:49:07 INFO - 'ts_paint', 06:49:07 INFO - 'tpaint', 06:49:07 INFO - 'sessionrestore', 06:49:07 INFO - 'sessionrestore_no_auto_restore', 06:49:07 INFO - 'tabpaint']}, 06:49:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:49:07 INFO - 'tests': ['tsvgx', 06:49:07 INFO - 'tsvgr_opacity', 06:49:07 INFO - 'tart', 06:49:07 INFO - 'tscrollx', 06:49:07 INFO - 'cart']}, 06:49:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:49:07 INFO - 'tsvgr_opacity', 06:49:07 INFO - 'tart', 06:49:07 INFO - 'tscrollx', 06:49:07 INFO - 'cart']}, 06:49:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:49:07 INFO - 'talos_options': ['--disable-e10s'], 06:49:07 INFO - 'tests': ['tp5o']}, 06:49:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:49:07 INFO - 'tests': ['tp5o']}, 06:49:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:49:07 INFO - 'talos_options': ['--disable-e10s', 06:49:07 INFO - '--xperf_path', 06:49:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:49:07 INFO - 'tests': ['tp5n']}, 06:49:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:49:07 INFO - 'talos_options': ['--xperf_path', 06:49:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:49:07 INFO - 'tests': ['tp5n']}}, 06:49:07 INFO - 'talos.zip': {'path': '', 06:49:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:49:07 INFO - Running post-action listener: _resource_record_post_action 06:49:07 INFO - ##### 06:49:07 INFO - ##### Running create-virtualenv step. 06:49:07 INFO - ##### 06:49:07 INFO - Running pre-action listener: _resource_record_pre_action 06:49:07 INFO - Running main action method: create_virtualenv 06:49:07 INFO - Creating virtualenv /builds/slave/test/build/venv 06:49:07 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 06:49:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:49:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:49:07 INFO - Using real prefix '/tools/python27' 06:49:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:49:07 INFO - Installing distribute.............................................................................................................................................................................................done. 06:49:10 INFO - Installing pip.................done. 06:49:10 INFO - Return code: 0 06:49:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:49:10 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')]} 06:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:10 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')]} 06:49:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:10 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 0x10b59fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5a35c8aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b43ee00>, '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 0x10b267db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b439b20>, '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.kZmTHcAoWZ/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.inGUcsUWGh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:49:10 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 06:49:10 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 06:49:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kZmTHcAoWZ/Render', 06:49:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:49:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:49:10 INFO - 'HOME': '/Users/cltbld', 06:49:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:49:10 INFO - 'LOGNAME': 'cltbld', 06:49:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:10 INFO - 'MOZ_NO_REMOTE': '1', 06:49:10 INFO - 'NO_EM_RESTART': '1', 06:49:10 INFO - 'PAGER': '/bin/cat', 06:49:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:49:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:10 INFO - 'PWD': '/builds/slave/test', 06:49:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:49:10 INFO - 'SHELL': '/bin/bash', 06:49:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inGUcsUWGh/Listeners', 06:49:10 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:49:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:49:10 INFO - 'USER': 'cltbld', 06:49:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:49:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:49:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:10 INFO - 'XPC_FLAGS': '0x0', 06:49:10 INFO - 'XPC_SERVICE_NAME': '0', 06:49:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:11 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 06:49:11 INFO - Cleaning up... 06:49:11 INFO - Return code: 0 06:49:11 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 06:49: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')]} 06:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49: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')]} 06:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49: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', '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 0x10b59fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5a35c8aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b43ee00>, '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 0x10b267db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b439b20>, '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.kZmTHcAoWZ/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.inGUcsUWGh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:49: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', 'psutil>=0.7.1'] in /builds/slave/test/build 06:49: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 psutil>=0.7.1 06:49:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kZmTHcAoWZ/Render', 06:49:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:49:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:49:11 INFO - 'HOME': '/Users/cltbld', 06:49:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:49:11 INFO - 'LOGNAME': 'cltbld', 06:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:11 INFO - 'MOZ_NO_REMOTE': '1', 06:49:11 INFO - 'NO_EM_RESTART': '1', 06:49:11 INFO - 'PAGER': '/bin/cat', 06:49:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:11 INFO - 'PWD': '/builds/slave/test', 06:49:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:49:11 INFO - 'SHELL': '/bin/bash', 06:49:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inGUcsUWGh/Listeners', 06:49:11 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:49:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:49:11 INFO - 'USER': 'cltbld', 06:49:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:49:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:11 INFO - 'XPC_FLAGS': '0x0', 06:49:11 INFO - 'XPC_SERVICE_NAME': '0', 06:49:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:11 INFO - Downloading/unpacking psutil>=0.7.1 06:49:11 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 06:49:11 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 06:49:11 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 06:49:11 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 06:49:11 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 06:49:11 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 06:49:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:49:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:49:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:49:13 INFO - Installing collected packages: psutil 06:49:13 INFO - Running setup.py install for psutil 06:49:13 INFO - building 'psutil._psutil_osx' extension 06:49:13 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 06:49:14 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 06:49:14 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 06:49:14 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 06:49:14 INFO - building 'psutil._psutil_posix' extension 06:49:14 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 06:49:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:49:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:49:14 INFO - ^ 06:49:14 INFO - 1 warning generated. 06:49:14 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 06:49:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:49:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:49:14 INFO - Successfully installed psutil 06:49:14 INFO - Cleaning up... 06:49:14 INFO - Return code: 0 06:49:14 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 06:49:14 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')]} 06:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:14 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')]} 06:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:14 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 0x10b59fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5a35c8aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b43ee00>, '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 0x10b267db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b439b20>, '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.kZmTHcAoWZ/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.inGUcsUWGh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:49:14 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 06:49:14 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 06:49:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kZmTHcAoWZ/Render', 06:49:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:49:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:49:14 INFO - 'HOME': '/Users/cltbld', 06:49:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:49:14 INFO - 'LOGNAME': 'cltbld', 06:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:14 INFO - 'MOZ_NO_REMOTE': '1', 06:49:14 INFO - 'NO_EM_RESTART': '1', 06:49:14 INFO - 'PAGER': '/bin/cat', 06:49:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:14 INFO - 'PWD': '/builds/slave/test', 06:49:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:49:14 INFO - 'SHELL': '/bin/bash', 06:49:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inGUcsUWGh/Listeners', 06:49:14 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:49:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:49:14 INFO - 'USER': 'cltbld', 06:49:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:49:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:14 INFO - 'XPC_FLAGS': '0x0', 06:49:14 INFO - 'XPC_SERVICE_NAME': '0', 06:49:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:15 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:49: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 06:49: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 06:49: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 06:49: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 06:49: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 06:49: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 06:49:17 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:49:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:49:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:49:17 INFO - Installing collected packages: mozsystemmonitor 06:49:17 INFO - Running setup.py install for mozsystemmonitor 06:49:17 INFO - Successfully installed mozsystemmonitor 06:49:17 INFO - Cleaning up... 06:49:17 INFO - Return code: 0 06:49:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:49:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:49:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:17 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 0x10b59fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5a35c8aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b43ee00>, '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 0x10b267db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b439b20>, '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.kZmTHcAoWZ/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.inGUcsUWGh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:49:17 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 06:49:17 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 06:49:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kZmTHcAoWZ/Render', 06:49:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:49:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:49:17 INFO - 'HOME': '/Users/cltbld', 06:49:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:49:17 INFO - 'LOGNAME': 'cltbld', 06:49:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:17 INFO - 'MOZ_NO_REMOTE': '1', 06:49:17 INFO - 'NO_EM_RESTART': '1', 06:49:17 INFO - 'PAGER': '/bin/cat', 06:49:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:49:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:17 INFO - 'PWD': '/builds/slave/test', 06:49:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:49:17 INFO - 'SHELL': '/bin/bash', 06:49:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inGUcsUWGh/Listeners', 06:49:17 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:49:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:49:17 INFO - 'USER': 'cltbld', 06:49:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:49:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:49:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:17 INFO - 'XPC_FLAGS': '0x0', 06:49:17 INFO - 'XPC_SERVICE_NAME': '0', 06:49:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:49:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:17 INFO - Downloading/unpacking blobuploader==1.2.4 06:49: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 06:49: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 06:49: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 06:49: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 06:49: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 06:49: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 06:49:20 INFO - Downloading blobuploader-1.2.4.tar.gz 06:49:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:49:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:49:20 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 06:49:20 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 06:49:20 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 06:49:20 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 06:49:20 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 06:49:20 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 06:49:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:49:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:49:21 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 06:49:21 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 06:49:21 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 06:49:21 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 06:49:21 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 06:49:21 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 06:49:21 INFO - Downloading docopt-0.6.1.tar.gz 06:49:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:49:21 INFO - Installing collected packages: blobuploader, requests, docopt 06:49:21 INFO - Running setup.py install for blobuploader 06:49:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:49:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:49:21 INFO - Running setup.py install for requests 06:49:22 INFO - Running setup.py install for docopt 06:49:22 INFO - Successfully installed blobuploader requests docopt 06:49:22 INFO - Cleaning up... 06:49:22 INFO - Return code: 0 06:49:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:22 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 0x10b59fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5a35c8aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b43ee00>, '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 0x10b267db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b439b20>, '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.kZmTHcAoWZ/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.inGUcsUWGh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:49:22 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 06:49:22 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 06:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kZmTHcAoWZ/Render', 06:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:49:22 INFO - 'HOME': '/Users/cltbld', 06:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:49:22 INFO - 'LOGNAME': 'cltbld', 06:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:22 INFO - 'MOZ_NO_REMOTE': '1', 06:49:22 INFO - 'NO_EM_RESTART': '1', 06:49:22 INFO - 'PAGER': '/bin/cat', 06:49:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:22 INFO - 'PWD': '/builds/slave/test', 06:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:49:22 INFO - 'SHELL': '/bin/bash', 06:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inGUcsUWGh/Listeners', 06:49:22 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:49:22 INFO - 'USER': 'cltbld', 06:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:22 INFO - 'XPC_FLAGS': '0x0', 06:49:22 INFO - 'XPC_SERVICE_NAME': '0', 06:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:49:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:49:22 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-p8A6Nl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:49:22 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-LWfEtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:49:22 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Ffjm7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:49:23 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-S3sGy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:49:23 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-k0dXn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:49:23 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-xLrnlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:49:23 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-lp7xpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:49:23 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-xgcneX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:49:23 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-iY9Ypg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:49:23 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-vX0Ti0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:49:23 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-4m0Wvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:49:23 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-GS5KEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:49:24 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-HXfhh2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:49:24 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Hgs9qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:49:24 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Yw4AjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:49:24 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-weGRp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:49:24 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-w8l8i5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:49:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:49:24 INFO - Running setup.py install for manifestparser 06:49:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:49:24 INFO - Running setup.py install for mozcrash 06:49:24 INFO - Running setup.py install for mozdebug 06:49:24 INFO - Running setup.py install for mozdevice 06:49:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:49:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:49:25 INFO - Running setup.py install for mozfile 06:49:25 INFO - Running setup.py install for mozhttpd 06:49:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:49:25 INFO - Running setup.py install for mozinfo 06:49:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:49:25 INFO - Running setup.py install for mozInstall 06:49:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:49:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:49:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:49:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:49:25 INFO - Running setup.py install for mozleak 06:49:25 INFO - Running setup.py install for mozlog 06:49:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:49:26 INFO - Running setup.py install for moznetwork 06:49:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:49:26 INFO - Running setup.py install for mozprocess 06:49:26 INFO - Running setup.py install for mozprofile 06:49:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:49:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:49:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:49:26 INFO - Running setup.py install for mozrunner 06:49:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:49:26 INFO - Running setup.py install for mozscreenshot 06:49:26 INFO - Running setup.py install for moztest 06:49:26 INFO - Running setup.py install for mozversion 06:49:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:49:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:49:27 INFO - Cleaning up... 06:49:27 INFO - Return code: 0 06:49:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:27 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 0x10b59fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5a35c8aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b43ee00>, '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 0x10b267db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b439b20>, '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.kZmTHcAoWZ/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.inGUcsUWGh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:49:27 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 06:49:27 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 06:49:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kZmTHcAoWZ/Render', 06:49:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:49:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:49:27 INFO - 'HOME': '/Users/cltbld', 06:49:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:49:27 INFO - 'LOGNAME': 'cltbld', 06:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:27 INFO - 'MOZ_NO_REMOTE': '1', 06:49:27 INFO - 'NO_EM_RESTART': '1', 06:49:27 INFO - 'PAGER': '/bin/cat', 06:49:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:27 INFO - 'PWD': '/builds/slave/test', 06:49:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:49:27 INFO - 'SHELL': '/bin/bash', 06:49:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inGUcsUWGh/Listeners', 06:49:27 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:49:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:49:27 INFO - 'USER': 'cltbld', 06:49:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:49:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:27 INFO - 'XPC_FLAGS': '0x0', 06:49:27 INFO - 'XPC_SERVICE_NAME': '0', 06:49:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:49:27 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-dzSChc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:49:27 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)) 06:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:49:27 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ti9Jx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:49:27 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)) 06:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:49:27 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-D0dyf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:49:27 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)) 06:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:49:27 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-fj_GkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:49:27 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)) 06:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:49:27 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-RYdH3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:49:27 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)) 06:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:49:27 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-BH8I4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:49:28 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)) 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:49:28 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-I0kLqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:49:28 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)) 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:49:28 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-OSEOzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:49:28 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)) 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:49:28 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-5MY3j2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:49:28 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)) 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:49:28 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-cnnIT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:49:28 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)) 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:49:28 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Bz_r_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:49:28 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)) 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:49:28 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-wbzg4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:49:28 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)) 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:49:28 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-YG4AtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:49:28 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)) 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:49:28 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-07I59a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:49:28 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)) 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:49:28 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-h9zbsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:49:28 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)) 06:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:49:28 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Ew9vVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:49:29 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)) 06:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:49:29 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-maRSjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:49:29 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)) 06:49:29 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)) 06:49:29 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)) 06:49:29 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)) 06:49:29 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)) 06:49:29 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)) 06:49:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:49:29 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 06:49:29 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 06:49:29 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 06:49:29 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 06:49:29 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 06:49:29 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 06:49:31 INFO - Downloading blessings-1.6.tar.gz 06:49:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:49:31 INFO - Installing collected packages: blessings 06:49:31 INFO - Running setup.py install for blessings 06:49:31 INFO - Successfully installed blessings 06:49:31 INFO - Cleaning up... 06:49:31 INFO - Return code: 0 06:49:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:49:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:49:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:49:32 INFO - Reading from file tmpfile_stdout 06:49:32 INFO - Current package versions: 06:49:32 INFO - blessings == 1.6 06:49:32 INFO - blobuploader == 1.2.4 06:49:32 INFO - docopt == 0.6.1 06:49:32 INFO - manifestparser == 1.1 06:49:32 INFO - mozInstall == 1.12 06:49:32 INFO - mozcrash == 0.17 06:49:32 INFO - mozdebug == 0.1 06:49:32 INFO - mozdevice == 0.48 06:49:32 INFO - mozfile == 1.2 06:49:32 INFO - mozhttpd == 0.7 06:49:32 INFO - mozinfo == 0.9 06:49:32 INFO - mozleak == 0.1 06:49:32 INFO - mozlog == 3.1 06:49:32 INFO - moznetwork == 0.27 06:49:32 INFO - mozprocess == 0.22 06:49:32 INFO - mozprofile == 0.28 06:49:32 INFO - mozrunner == 6.11 06:49:32 INFO - mozscreenshot == 0.1 06:49:32 INFO - mozsystemmonitor == 0.0 06:49:32 INFO - moztest == 0.7 06:49:32 INFO - mozversion == 1.4 06:49:32 INFO - psutil == 3.1.1 06:49:32 INFO - requests == 1.2.3 06:49:32 INFO - wsgiref == 0.1.2 06:49:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:49:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:32 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 0x10b59fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5a35c8aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b43ee00>, '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 0x10b267db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b439b20>, '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.kZmTHcAoWZ/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.inGUcsUWGh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:49:32 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 06:49:32 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 06:49:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kZmTHcAoWZ/Render', 06:49:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:49:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:49:32 INFO - 'HOME': '/Users/cltbld', 06:49:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:49:32 INFO - 'LOGNAME': 'cltbld', 06:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:32 INFO - 'MOZ_NO_REMOTE': '1', 06:49:32 INFO - 'NO_EM_RESTART': '1', 06:49:32 INFO - 'PAGER': '/bin/cat', 06:49:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:32 INFO - 'PWD': '/builds/slave/test', 06:49:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:49:32 INFO - 'SHELL': '/bin/bash', 06:49:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inGUcsUWGh/Listeners', 06:49:32 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:49:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:49:32 INFO - 'USER': 'cltbld', 06:49:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:49:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:32 INFO - 'XPC_FLAGS': '0x0', 06:49:32 INFO - 'XPC_SERVICE_NAME': '0', 06:49:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:49:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:32 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)) 06:49:32 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)) 06:49:32 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)) 06:49:32 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)) 06:49:32 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)) 06:49:32 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)) 06:49:32 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)) 06:49:32 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)) 06:49:32 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)) 06:49:32 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)) 06:49:32 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)) 06:49:32 INFO - Cleaning up... 06:49:32 INFO - Return code: 0 06:49:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:49:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b59fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5a35c8aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b43ee00>, '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 0x10b267db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b439b20>, '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.kZmTHcAoWZ/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.inGUcsUWGh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:49:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:49:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:49:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kZmTHcAoWZ/Render', 06:49:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:49:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:49:32 INFO - 'HOME': '/Users/cltbld', 06:49:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:49:32 INFO - 'LOGNAME': 'cltbld', 06:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:32 INFO - 'MOZ_NO_REMOTE': '1', 06:49:32 INFO - 'NO_EM_RESTART': '1', 06:49:32 INFO - 'PAGER': '/bin/cat', 06:49:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:32 INFO - 'PWD': '/builds/slave/test', 06:49:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:49:32 INFO - 'SHELL': '/bin/bash', 06:49:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inGUcsUWGh/Listeners', 06:49:32 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:49:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:49:32 INFO - 'USER': 'cltbld', 06:49:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:49:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:32 INFO - 'XPC_FLAGS': '0x0', 06:49:32 INFO - 'XPC_SERVICE_NAME': '0', 06:49:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:49:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:32 INFO - Downloading/unpacking jsonschema 06:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:49:35 INFO - Downloading/unpacking functools32 (from jsonschema) 06:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:35 INFO - Downloading functools32-3.2.3-2.tar.gz 06:49:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:49:35 INFO - warning: no files found matching '*.txt' 06:49:35 INFO - no previously-included directories found matching 'build' 06:49:35 INFO - no previously-included directories found matching 'dist' 06:49:35 INFO - no previously-included directories found matching '.git*' 06:49:35 INFO - Installing collected packages: jsonschema, functools32 06:49:35 INFO - Running setup.py install for functools32 06:49:35 INFO - warning: no files found matching '*.txt' 06:49:35 INFO - no previously-included directories found matching 'build' 06:49:35 INFO - no previously-included directories found matching 'dist' 06:49:35 INFO - no previously-included directories found matching '.git*' 06:49:35 INFO - Successfully installed jsonschema functools32 06:49:35 INFO - Cleaning up... 06:49:35 INFO - Return code: 0 06:49:35 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:49:35 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')]} 06:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:35 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')]} 06:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:49:35 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 0x10b59fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5a35c8aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b43ee00>, '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 0x10b267db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b439b20>, '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.kZmTHcAoWZ/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.inGUcsUWGh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 06:49:35 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 06:49:35 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 06:49:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kZmTHcAoWZ/Render', 06:49:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:49:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:49:35 INFO - 'HOME': '/Users/cltbld', 06:49:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:49:35 INFO - 'LOGNAME': 'cltbld', 06:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:35 INFO - 'MOZ_NO_REMOTE': '1', 06:49:35 INFO - 'NO_EM_RESTART': '1', 06:49:35 INFO - 'PAGER': '/bin/cat', 06:49:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:35 INFO - 'PWD': '/builds/slave/test', 06:49:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:49:35 INFO - 'SHELL': '/bin/bash', 06:49:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inGUcsUWGh/Listeners', 06:49:35 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:49:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:49:35 INFO - 'USER': 'cltbld', 06:49:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:49:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:35 INFO - 'XPC_FLAGS': '0x0', 06:49:35 INFO - 'XPC_SERVICE_NAME': '0', 06:49:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:49:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:49:36 INFO - Downloading/unpacking flake8 06:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:38 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:49:38 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:38 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:49:38 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:39 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:49:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:49:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:49:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:49:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:49:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:49:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:49:39 INFO - no previously-included directories found matching 'docs/_build' 06:49:39 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:49:39 INFO - Running setup.py install for pep8 06:49:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:49:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:49:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:49:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:49:39 INFO - no previously-included directories found matching 'docs/_build' 06:49:40 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:49:40 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:49:40 INFO - Cleaning up... 06:49:40 INFO - Return code: 0 06:49:40 INFO - Running post-action listener: _resource_record_post_action 06:49:40 INFO - Running post-action listener: _start_resource_monitoring 06:49:40 INFO - Starting resource monitoring. 06:49:40 INFO - ##### 06:49:40 INFO - ##### Running install step. 06:49:40 INFO - ##### 06:49:40 INFO - Running pre-action listener: _resource_record_pre_action 06:49:40 INFO - Running main action method: install 06:49:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:49:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:49:40 INFO - Reading from file tmpfile_stdout 06:49:40 INFO - Detecting whether we're running mozinstall >=1.0... 06:49:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:49:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:49:40 INFO - Reading from file tmpfile_stdout 06:49:40 INFO - Output received: 06:49:40 INFO - Usage: mozinstall [options] installer 06:49:40 INFO - Options: 06:49:40 INFO - -h, --help show this help message and exit 06:49:40 INFO - -d DEST, --destination=DEST 06:49:40 INFO - Directory to install application into. [default: 06:49:40 INFO - "/builds/slave/test"] 06:49:40 INFO - --app=APP Application being installed. [default: firefox] 06:49:40 INFO - mkdir: /builds/slave/test/build/application 06:49:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:49:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:50:13 INFO - Reading from file tmpfile_stdout 06:50:13 INFO - Output received: 06:50:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:50:13 INFO - Running post-action listener: _resource_record_post_action 06:50:13 INFO - ##### 06:50:13 INFO - ##### Running run-tests step. 06:50:13 INFO - ##### 06:50:13 INFO - Running pre-action listener: _resource_record_pre_action 06:50:13 INFO - Running main action method: run_tests 06:50:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:50:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:50:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:50:13 INFO - 2016-04-23 06:50:13.741 screenresolution[1922:16136] starting screenresolution argv=screenresolution get 06:50:13 INFO - 2016-04-23 06:50:13.744 screenresolution[1922:16136] Display 0: 1600x1200x32@60 06:50:13 INFO - 2016-04-23 06:50:13.750 screenresolution[1923:16138] starting screenresolution argv=screenresolution list 06:50:13 INFO - Available Modes on Display 0 06:50:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:50:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:50:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:50:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:50:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:50:13 INFO - Intel Iris: 06:50:13 INFO - Chipset Model: Intel Iris 06:50:13 INFO - Type: GPU 06:50:13 INFO - Bus: Built-In 06:50:13 INFO - VRAM (Dynamic, Max): 1536 MB 06:50:13 INFO - Vendor: Intel (0x8086) 06:50:13 INFO - Device ID: 0x0a2e 06:50:13 INFO - Revision ID: 0x0009 06:50:13 INFO - Displays: 06:50:13 INFO - Display: 06:50:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:50:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:50:13 INFO - Display Serial Number: 1600x1200 60 06:50:13 INFO - Main Display: Yes 06:50:13 INFO - Mirror: Off 06:50:13 INFO - Online: Yes 06:50:13 INFO - Rotation: Supported 06:50:13 INFO - Return code: 0 06:50:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:50:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:50:13 INFO - Python 2.7.3 06:50:13 INFO - Return code: 0 06:50:13 INFO - grabbing minidump binary from tooltool 06:50:13 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')]} 06:50:13 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 0x10b43ee00>, '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 0x10b267db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b439b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:50:13 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 06:50:13 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 06:50:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:50:14 INFO - Return code: 0 06:50:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:50:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:50:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:50:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:50:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:50:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:50:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:50:15 INFO - Return code: 0 06:50:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--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 06:50:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0127 --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 06:50:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kZmTHcAoWZ/Render', 06:50:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:50:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:50:15 INFO - 'HOME': '/Users/cltbld', 06:50:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:50:15 INFO - 'LOGNAME': 'cltbld', 06:50:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:50:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:50:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:50:15 INFO - 'MOZ_NO_REMOTE': '1', 06:50:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:50:15 INFO - 'NO_EM_RESTART': '1', 06:50:15 INFO - 'PAGER': '/bin/cat', 06:50:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:50:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:50:15 INFO - 'PWD': '/builds/slave/test', 06:50:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:50:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:50:15 INFO - 'SHELL': '/bin/bash', 06:50:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inGUcsUWGh/Listeners', 06:50:15 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 06:50:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:50:15 INFO - 'USER': 'cltbld', 06:50:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:50:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:50:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:50:15 INFO - 'XPC_FLAGS': '0x0', 06:50:15 INFO - 'XPC_SERVICE_NAME': '0', 06:50:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:50:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--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 06:50:15 INFO - mozversion application_buildid: 20160423050055 06:50:15 INFO - mozversion application_changeset: 2be765b599b2d90103013526ef8ebe684b66b218 06:50:15 INFO - mozversion application_display_name: Nightly 06:50:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:50:15 INFO - mozversion application_name: Firefox 06:50:15 INFO - mozversion application_remotingname: firefox 06:50:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:50:15 INFO - mozversion application_vendor: Mozilla 06:50:15 INFO - mozversion application_version: 48.0a1 06:50:15 INFO - mozversion platform_buildid: 20160423050055 06:50:15 INFO - mozversion platform_changeset: 2be765b599b2d90103013526ef8ebe684b66b218 06:50:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:50:15 INFO - mozversion platform_version: 48.0a1 06:50:15 INFO - using testdate: 1461419415 06:50:15 INFO - actual date: 1461419415 06:50:15 INFO - starting webserver on 'localhost:52278' 06:50:15 INFO - SUITE-START | Running 2 tests 06:50:15 INFO - TEST-START | dromaeo_css 06:50:15 INFO - operating with platform_type : mac_ 06:50:15 INFO - Initialising browser for dromaeo_css test... 06:50:15 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpNRkQWf/profile http://localhost:52278/getInfo.html) 06:50:17 INFO - PROCESS | 1941 | __metrics Screen width/height:1600/1200 06:50:17 INFO - PROCESS | 1941 | colorDepth:24 06:50:17 INFO - PROCESS | 1941 | Browser inner width/height: 1024/689 06:50:17 INFO - PROCESS | 1941 | __metrics 06:50:17 INFO - PROCESS | 1941 | 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" 06:50:17 ERROR - PROCESS | 1941 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:50:18 INFO - TEST-INFO | 1941: exit 0 06:50:18 INFO - Browser initialized. 06:50:18 INFO - Running cycle 1/1 for dromaeo_css test... 06:50:18 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpNRkQWf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:50:30 INFO - PROCESS | 1943 | RSS: Main: 239255552 06:50:30 INFO - PROCESS | 1943 | 06:51:21 INFO - PROCESS | 1943 | 1461419481802 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] 06:53:31 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52278/tests/dromaeo/cssquery-ext.html) 06:53:33 INFO - PROCESS | 1943 | RSS: Main: 373448704 06:53:33 INFO - PROCESS | 1943 | 06:56:36 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52278/tests/dromaeo/cssquery-ext.html (next: http://localhost:52278/tests/dromaeo/cssquery-jquery.html) 06:56:37 INFO - PROCESS | 1943 | RSS: Main: 374816768 06:56:37 INFO - PROCESS | 1943 | 06:59:39 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52278/tests/dromaeo/cssquery-mootools.html) 06:59:39 INFO - PROCESS | 1943 | RSS: Main: 377888768 06:59:39 INFO - PROCESS | 1943 | 07:02:41 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52278/tests/dromaeo/cssquery-prototype.html) 07:02:42 INFO - PROCESS | 1943 | RSS: Main: 377032704 07:02:42 INFO - PROCESS | 1943 | 07:05:45 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52278/tests/dromaeo/cssquery-yui.html) 07:05:46 INFO - PROCESS | 1943 | RSS: Main: 345214976 07:05:46 INFO - PROCESS | 1943 | 07:08:49 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52278/tests/dromaeo/cssquery-yui.html (next: http://localhost:52278/tests/dromaeo/cssquery-dojo.html) 07:08:49 INFO - PROCESS | 1943 | RSS: Main: 386465792 07:08:49 INFO - PROCESS | 1943 | 07:08:49 INFO - PROCESS | 1943 | __start_tp_report 07:08:49 INFO - PROCESS | 1943 | _x_x_mozilla_page_load 07:08:49 INFO - PROCESS | 1943 | _x_x_mozilla_page_load_details 07:08:49 INFO - PROCESS | 1943 | |i|pagename|runs| 07:08:49 INFO - PROCESS | 1943 | |0;dojo.html;3318.9170270743234;3418.2821607462447;3448.0690213642333;3449.8620055197794;3489.6859282664554;5639.548836093113;5737.856553586163;5745.827625171251;5783.21926539917;5806.73869675864;5704.714764261791;5763.769449222026;5883.264591926009;5927.2294601701715;5930.199423077894;5137.5633071188995;5156.458571849958;5190.4031036430915;5190.636655434121;5269.920951185735;5331.253624492555;5351.759170837302;5367.597430192732;5386.165144402624;5389.973050134744;5124.103281925648;5200.635955483118;5218.086834803894;5222.138347172712;5313.840584782462;5188.507091826271;5190.97404512975;5237.554807841338;5247.291615631879;5299.788008479694;5051.873703157414;5154.53609175176;5182.870428239286;5202.271681964528;5275.393329767087;5496.890062198733;5631.8592035199035;5636.408177141423;5639.238702775114;5640.689762063085;6126.181801870944;6216.7824126155365;6224.751009959597;6235.345288744707;6238.719257633417;5448.91826622601;6217.191765070512;6222.66736743981;6284.968575157141;6289.748410063592;5675.177099320658;5760.193572899755;5773.595848290579;5775.942240577624;5776.797812076557;5076.568491678248;5436.891262174734;6186.1648677428875;6222.439980401786;6311.116443697886;5353.197020446886;6018.15745795589;6169.629822210682;6184.319724830265;6188.4739797116845;6121.296050954108;6182.258129024546;6207.937920620826;6270;6273.686315684197;5171.63798534099;5480.972595136999;5682.488576028178;6128.869808884179;6246.781362652377;6180.907286390708;6250.812475625738;6260.718267677966;6267.185265915493;6278.560500764994;4856.8300109495995;4890.657653964188;4939.357883475125;4944.233643785219;4952.603791696655;4447.2439685253585;4448;4470.217711900469;4494.16857881293;4500.459944806644;5653.886922261532;5673.687947162945;5683.516901063374;5685.005124103265;5693.430656934356;6153.384661533901;6171.13604095427;6218.160548326008;6220.066989951543;6245.281792593819;5706.42935706426;5714.228579141846;5720.799772007959;5748.511376532958;5766.769329226868;5382.434844341366;5387.6228663993925;5388.380336261302;5406.621536492407;5406.756695948692;6209.9068513972325;6300.086487459384;6302.747890084345;6325.05124231369;6347.206599175103;5261.868453288545;6242.375762423721;6260.154879091265;6280.026595877646;6281.4660753834605;6305.234534330395;6362.300146983921;6372.07604897278;6372.36276372359;6386.074019267087;6070.2108725865355;6111.804454079419;6131.774283661862;6204.069389591597;6226.937730622636;6255.593386429868;6257;6266.561340706106;6273.090401891609;6300.59046161998;5255.816046438356;6130.01919692847;6131.908021379772;6149.477294430024;6170.074488826712;5985.102234664835;6046.032634778414;6053.939460605514;6057.42454466825;6108.918862504732;5923.822285331447;5961.7615295387695;5969.403059693996;5970.731317090828;6000.669963152069;5104.285400043923;6090.056041313604;6136.018237082046;6153.569250152446;6154.599951003171;4850.708957462563;5979.192808970907;5994.28068631767;5998.310194327708;6006.099085137264 07:08:49 INFO - PROCESS | 1943 | |1;ext.html;507.29232720355105;527.0434161995977;563.4506356302608;594.7115648910278;597.1818608506347;30701.692983070163;38747.612523874785;39860.60139398605;39886.800565997204;39913.00217494564;35111.82444087783;42234.73295801131;42996;43025;43120;8561.1866872647;9392.436453812776;9628.71113866585;9636.759081022961;9656.420614763138;8681.1318868113;11478.942605287004;11768.52925882968;11778.998785103277;11782.705432364217;11424.771504569904;11552.653420397359;11554.422278886022;11558.768824623545;11563.710907227345;8600.956995214983;11425.942870285595;11444.942775286112;11530.654080377602;11720.355380454072;11502.827457588142;11605.419729013516;11629.127815413878;11640.195769655717;11645.359505227209;39349.60650393488;39972.40041399352;40003.39994900079;40007.79996100001;40017.39973900394;3074.9231269218226;3168.350488149924;3248.025592322294;3407.8296085195643;4021.4369988201674;22993.310200694006;28212.43575128486;29236.41527169466;29278.853605731834;29340.413191736257;2623.2130360891847;2643.550596398617;2723.319170207448;2799.8040137190405;2815.85920703964;428404;439840;442107;442408;442825;64459.03311450332;64975.67512162409;65250.02124968129;65262.673686631264;65386.346136538974;67997;68291.3170868285;68368.97446538306;68442.65778671174;68516;17482.65034699299;17670;17687.557811054827;17701.822981770274;17740.733888991675;12144.271343719405;12571.685707857378;13058.869411305765;13342;13431.93284033593;19544.63368154282;20228.797712022984;20229.190832366836;20255.392338229878;20324.085416156307;7377.299156580117;7502.399808015346;7662.96168519165;8017.639206235734;8234.447321330286;992.7121134870806;1001.0790073132736;1003.4481035430484;1049.5434485998614;1056.7252514346171;29780.2554936124;29845.552316715268;30365.937192198166;30644;30702.53946190809;1088.983665245022;1094;1135.6252436696036;1155.1047937848066;1185.7213554814612;14100.717985640435;14201.934854885925;15479.767803482957;15811.209439527978;16039.04812711573;644984;652134;654212;655424;655607;62605.68697156576;62631.6868415655;63489.36510634927;63528.36471635317;63609.36390636034;1356.593022093368;1377.6280404290985;1410.400579753621;1423.6156237815733;1462.8975971682094;25157.748422516273;30491.390172196876;30505.389892201587;30589.23526911845;31269;52520;56967;56979;57045;57096;475760.6211969057;492347;493013;493157;493345;23884.164054258017;24443.877780610983;26455.206343809718;26783.598246025937;26919.461610767285;43099;43167.13665726731;43696;44121.77939110284;44676.32985505155;34398.96803095911;36410.8179459101;36910.446343304335;37007.814960925025;37020;34833.30333393368;35483.64516354907;35549.822250889614;36380.090497737765;36385.818070909474;36548;36799;37478.06304842401;38692.8392148236;39316.41025384564;31105.222369440944;33494.66505335013;33510.32979340448;33697.326053478304;33740.325193495504;20072.89963550173;24434.755652443248;24949.625755613317;25056;25080.498390032462 07:08:49 INFO - PROCESS | 1943 | |2;jquery.html;397.3245482679444;397.37413573621546;399.3191608307835;402.4164960806831;413.80344336440174;14420.711585768304;19559.902200489014;20600.072996715164;20662.276820311294;21312.36062918115;16313.184340782973;18474.538136546595;20556.177752889867;20592.588148237064;21310.89344553275;5218;5904.468597826206;6079.939200607982;6079.969600151993;6115.449609535141;5372;5972.641641501502;6057.697115144248;6071.908921366183;6119.082137679361;5882.3529411764885;6066.150738896558;6088.726007329658;6089.725962331706;6091.208142941411;6016.067509536029;6034.24571928509;6069.969650151766;6092.939070609325;6097.817065488065;5111.974440127776;5291.523762861322;5292.391374991848;5299.125644268691;5312.76092575835;22861;25001.4999700005;25830.612540811904;26815.19554413371;27295.590566141524;1928.855335849803;2180.1279488204686;2239.1267405711787;2321.454458202321;2375.693368647237;9734.707958761248;10024.147947424477;10100.898991010143;10215.642452514126;10218.897811021869;1700.0649642696467;1752.8948263104255;1754.0615770562688;1774.343706904702;1798.4784412520353;108763;111602;112009.87990120077;112349.4382528082;112880;11565.183489448462;11656.70858228534;11689.649310520697;11707.88292117068;11905.642830715093;15262.236888155549;15329.6167595811;15338.386464541294;15344.158700955957;15357.769633455284;7441.479096463196;7879.684812607432;8219.712310069119;8797.032326444087;8961.731148065439;8666.795445202251;8817.691380801642;8871.689490867917;8964.820703585892;8982.550872456351;4617.861464156013;4702.388689470347;4727.101850648366;4762.523747625279;4779.689320194177;1589.8235305873802;1907.6947688369794;1967.390109066194;1969.3402710091993;1969.4682435742557;896.3008853094597;941.5292353823088;966.4587830814626;975.8048390321964;984.9753756156153;18771.530711732317;19453.902730486257;21229.256976006076;21363.46591335229;21554.461138471663;986.8075725233654;1002.2783595811004;1003.9799204015878;1027.1525991057408;1054.5360041581832;10077.445740484343;11318;11322.150838686966;11365.545378184945;11509.194356394972;556247.2187639116;565243.1737841284;570464;584066;584171.0791446101;121742.78257217491;124895;128089;128704;132774;1026.312370711625;1042.0673497220098;1080.5623722392443;1103.823388257875;1106.2145876427826;18422.90788546049;18648.81351186525;18744.437666870017;18862.717059244398;18990.24039038423;48929.755351223015;49538;51296.48703513067;51350;52276;73504;73754;75539.62230188814;75979.62010189914;76464.61767691126;6571.342865713582;7779.105402878741;7889.278199021329;8287.337013038821;8637.049924508174;12314.384280786104;12428.875711243134;12574.937125314314;12764.680882978;12828.743425131632;10909.945450272699;11133;11293.491792869176;11343.149263805082;11363.488643010918;10321.74195645115;10410.219233557362;10850.620228291971;11211.66365009051;11472.541098355972;11234;11243.325400475998;11416.029637480908;11832.881671183179;11881.465334060162;8210.220029097227;8341.708040218564;8437.367197460339;8451.028131764926;8497.983215958246;9870.605175793175;10811.6215932442;10998.010179083922;11112.388818615054;11129.109671226444 07:08:49 INFO - PROCESS | 1943 | |3;mootools.html;1386.5008596905107;1566.2716836670945;1572.0017788704174;1822.1709122349332;1980.2178139634855;7924.960375198123;8581.485110893344;8790.38467307288;8800.91199088009;8802.383833131684;8408.369372297071;8641.783955401119;8648.65405383784;8652.91347086529;8661.133886611336;2152.2467136502178;2351.9176828810996;2429.5626787178303;2437.341917682225;2460.4095017195923;2468.6173643085262;2491.950160996779;2492.5887228607257;2496.325991982164;2497.325722055053;2415.311636183688;2460;2468.1978357034036;2468.7654672806057;2469.5678256305164;2142.1110239250693;2360.091364824538;2440.890159942798;2440.9267721968367;2441.1578005588044;2428.6357046443;2441.279822452381;2444.877756112205;2448.7673671001226;2450.5711500487523;8705.825883482295;8761.474311541328;8763.298936085162;8771.342149338825;8788.384813063089;5115.181570948667;5341.03861304965;5381;5383.031054410205;5401.9736250112355;3502.334556434295;3513.3675938331344;3610;3625.8730944417084;3627.9455808162897;1003.2676146413087;1021.5300961557692;1023.3859684189501;1027.6300531808542;1032.3702541449709;11878.287302761775;11912.463939122761;11924.224925379822;11932.343721095336;11932.821007684892;3857.5949525300002;3895.7467764595212;4927.802887884516;4934.3338649282605;4939.506049395031;10012.199024078056;10022.599096036078;10024.54889529973;10027.649032283834;10033;5425.077736784756;5427.1316589345515;5450.264214331015;5459.590530710133;5468.043092458804;4423.336499525032;4586.2661974083985;4760.071786001697;4913.70517768935;4924.482929292444;3907.7264591478324;3937.7440466369594;4034.899127521777;4053.8986525337104;4067.8576249831112;1342.90599658023;1348.4471366739785;1351.3108314759543;1355.5391167003265;1362.8092067110613;726.2773540327303;892.4912799704236;925.2366797392178;936.8663916660917;946.3030363464072;5585.273914391103;5853.590248682636;6028.547858910511;6048.213732214784;6204.410580994799;464.3638215644589;471.3824889394904;476.6187050359698;482.39700374531833;483.4174819342684;3461.2558299965626;3938.0942383252013;3984.362501999666;4039.2325458162854;4094.733842300241;10350.689479315633;10863.891361086446;10869.83695244572;10877.782444351069;10883.67348979532;10488.842667359995;10674.146068314516;10687.465626718633;10757.139428845674;10820.188485863591;8007.519548827089;8107.351411887035;8135.227153420416;8166.714165004197;8183.918160818315;5759;5826.41735826414;5860.677662728591;5887.293524777054;5939.138824870283;9715.611375544899;9940.254480913816;9973.202143828621;10028.997100289913;10050.39697618116;9750.951245243728;10030.548625311732;10074.69775906724;10128.34165779222;10136.847947280945;7402.148752893486;7406.592637404994;7419;7473;7481;6848.280930502226;8057.315128214167;8180.263776260165;8192.34461243111;8195.221453961864;7192.244814294633;7199.568025918462;7451.2921272479025;7453.440991925519;7528.096628404626;7137.536060156073;7266.455015873937;7340.192578816219;7376.299251571092;7479.439042071757;6869.381755642016;6913.654317284015;6938.375546200866;6944.062551555476;6948.062011628567;4792.760361981817;4932.161532539405;5010.173321401928;5034.496550344937;5076.466332360294;4306.784660767012;4930;5042.647014708935;5055.823046193365;5101.566366858859 07:08:49 INFO - PROCESS | 1943 | |4;prototype.html;320.4920201480653;323.6633900743227;329.7856393344327;332.04868053028065;337.1369294605811;5589.888202235953;5875.5005824504915;5904.793332233373;5920.822375328737;5924.911126333098;5871.354151043392;5874.265716785402;5878.884191378565;5888.44059814318;5896.970515147429;3882.524640636554;4018.276710192164;4200.453940987667;4356.969802932291;4395.384646149541;4227.619514243717;4328.2209202343565;4409.581089796464;4448.354988526662;4479.731216127043;4456.59890609845;4458.732476051431;4466.82132714691;4495.685302028843;4499.820007199708;3468.7745296555645;4180.540140584533;4426.18115648606;4446.288593825005;4503.707259028169;3829.9808500957597;3832.405977073558;3877.2827027000094;3884.844606215748;3894.6300101490597;5533.94854977557;5584.106542953149;5604.887902241932;5622.943770562283;5650.90916360945;4651.534846515356;4968.608789538935;5177.689338639656;5182.352205974254;5209.2446595243855;6016.639001659914;6132.386761323876;6167.198264225622;6168;6202;4277.294246449332;4292.763897985641;4292.871213863589;4298.935515967263;4315.223259813232;6235.625862448268;6294.527910406738;6317.6209427434505;6446.161998940154;6647.501437392167;4291.785410729451;4296.720713153635;4351.216780979391;4353.521112677603;4353.6081752641785;3898.6159913230395;4425.247707889666;4426.18115648606;4436.179306828247;4442.244818380818;4841.540053694894;4931.964287499593;5085.720285384339;5101.770420331094;5122.334096567422;5419.729013549307;5535.169724541271;5888.704485013291;6231.626102433868;6241.285041007176;1503.5414198669291;1531.663034132489;1537.6617144228253;1542.383046781274;1542.691461707663;988.7083310423442;1008.3949630221955;1015.5277795824977;1018.4805749067881;1031.3038698878227;5375.532781356003;5797.797077102281;5824.650520968671;5856.531477481792;5862.648241105548;6197.101420294032;6350.237971443364;6457.903131453032;6469.45009674183;6534.117894084333;4868.95131048692;4953.678010929278;5634.464725851038;5728.977586369844;5862.472377485961;2866.584345269924;3394.321135772806;3419.5212670226188;3426.794392336418;3429.3141371725756;6473.190851143607;6758.594484330956;6820.35206655367;6831.64308313631;6838.760643377557;5914.2498738087;6254.6247225166635;6366.172397588284;6675.659472422043;6683.460285091634;6552.312007239267;6586.70359833799;6809.604031173598;6812.863742725118;6822.317768223138;5293.947060529345;5871.588988770745;6459.773907913201;6468.353164683494;6476.158099447041;5159.767810448464;5450.31871016123;6373.840653983688;6420.683759829317;6434.581752186093;5602.159676048625;5831.804480081658;6535.202819224561;6654.90017649726;6859.931400686129;4328.696991210585;4354.9782251088545;4362.694611377173;4367.956320436755;4380.9780951095045;6230.968845155927;6497;6499.512536559682;6499.967500162469;6511.21865376139;5793.304803423616;6284.151639528605;6344.080108384166;6353.951597986281;6359.777407790705;6186.226721659793;6291.559590828598;6305.495560355253;6327.55707100517;6328.936710632835;6157.599756015845;6164.630122192682;6167.938320616916;6168.691565421621;6184.257889053342;5557.833265002057;6072.906876762048;6300.306966233802;6317.4630156437215;6344.873102537831;5288.550473209821;5330.600204984564;5840.649561026352;5862.736176872137;6046.818595442144 07:08:49 INFO - PROCESS | 1943 | |5;yui.html;583.5856541855283;669.4134900286319;674.3896773420057;745.1133151549656;802.7993001749562;12618.495260189582;15020.24898755062;15027.549173524798;15223.391064357413;15345.846541534582;13999.090059146149;14062.15627062377;14077.788833167511;14379.49671761486;15690.980086294383;922.6540047482194;933.1135421349745;934.8971613122551;936.5551363102505;936.8313703533395;910.836049511091;933.6405483888718;933.6545478173085;936.7377134402378;936.9880529028633;979.211734553684;980.2599037726507;981.5779214937573;981.906718861707;984.2322988069318;888.564603344363;895.9641614335419;976.4092723902008;976.8046390721884;978.5351957820014;729.9707412548281;732.1068296678044;732.3591857125016;732.7618523979675;733.2044731466366;14765.852341476555;14809.259537023214;14877.925610371878;14888.62778430537;14901.850981490155;12617.621471355875;12690.87309126906;12710.618681439571;12851;13455.461781528727;10032.197424206046;10091.596336146546;10257.384556926609;10349.379037257713;10519.947400263027;3236.8543415546355;3417.8974630761118;3480.1647604574973;3546.2552863898613;3731.8880433586764;78202;78850.99999999943;79809.00477488051;79891.01362329708;80699.7895031569;9307.20888724466;9537.427754334762;9750.658726944523;9788.804223915624;9855.063768941938;21745.67381489279;21751.67372489414;21770.455738606663;21793.67309490359;21799;7468;7632.771016869503;7701.537907725442;7705;7768.028996375453;4044.716869819143;4098.139390727921;4105.486814148231;4170.311898536768;4208.6366809614165;3412.3175364927115;3484.1289677580603;3484.181217413906;3491.30173965208;3492.511048453219;581.8798812286332;619.3527763487167;626.6459450410504;648.4275631593367;659.868026394723;556.2546188107955;587.8412828536271;596.3618927747269;598.374698440131;603.7916918663054;6583.446052178397;7447.664855081534;7728.420368472274;7875.645595948196;7985.560794156261;543.6602123672704;570.9314882214161;575.789836709598;598.1088178613921;617.7312868901952;3634.83643236055;3803.5245594300713;3842.6733727633464;4006.599340066028;4118.135191609736;100906.4954675222;101023.49488252512;101825;102297;103901;80099;80455.59772201102;80487.7926831098;80641;82009;67112.32887671159;72605;72718.27281727221;74003.62998184975;74194;7596.164421913474;7629.4413219202;7713.074431068083;7751.534907905544;7767.495112817649;45346;45990;46165.07596854305;46165.38421155243;47543.76228118837;63080.73838523112;66681.33296667428;67886;67915.66042169757;68301.87735919727;9875.703728888146;9916.752081198028;9936.950315248667;10173.489462286778;10535.63125290611;11028.22802403824;11034.558617655204;11352.886471135513;11435.142364322875;11503;10325.070743633109;10370.170386369224;10555.780486951246;10753.89246107529;10756.784864302826;10357.896421035995;10366.429846358222;10767.946160269148;10785.366338602309;10885.836712449161;10223.897761022294;10387.68836934893;10487.213458990454;10647.733806654896;10829.512671929624;8723.65105395777;9002.234810040958;9035.096490350912;9104.499252541174;9241.353105282566;7212.386947109554;8835.514046727492;8920.509371984603;8999;9048.41185322952 07:08:49 INFO - PROCESS | 1943 | __end_tp_report 07:08:49 INFO - PROCESS | 1943 | __start_cc_report 07:08:49 INFO - PROCESS | 1943 | _x_x_mozilla_cycle_collect,1324 07:08:49 INFO - PROCESS | 1943 | __end_cc_report 07:08:49 INFO - PROCESS | 1943 | __startTimestamp1461420529661__endTimestamp 07:08:49 INFO - PROCESS | 1943 | 07:08:49 INFO - PROCESS | 1943 | ------- Summary: start ------- 07:08:49 INFO - PROCESS | 1943 | Number of tests: 6 07:08:49 INFO - PROCESS | 1943 | 07:08:49 INFO - PROCESS | 1943 | [#0] dojo.html Cycles:165 Average:5699.70 Median:5806.74 stddev:628.67 (10.8%) stddev-sans-first:602.22 07:08:49 INFO - PROCESS | 1943 | Values: 3318.9 3418.3 3448.1 3449.9 3489.7 5639.5 5737.9 5745.8 5783.2 5806.7 5704.7 5763.8 5883.3 5927.2 5930.2 5137.6 5156.5 5190.4 5190.6 5269.9 5331.3 5351.8 5367.6 5386.2 5390.0 5124.1 5200.6 5218.1 5222.1 5313.8 5188.5 5191.0 5237.6 5247.3 5299.8 5051.9 5154.5 5182.9 5202.3 5275.4 5496.9 5631.9 5636.4 5639.2 5640.7 6126.2 6216.8 6224.8 6235.3 6238.7 5448.9 6217.2 6222.7 6285.0 6289.7 5675.2 5760.2 5773.6 5775.9 5776.8 5076.6 5436.9 6186.2 6222.4 6311.1 5353.2 6018.2 6169.6 6184.3 6188.5 6121.3 6182.3 6207.9 6270.0 6273.7 5171.6 5481.0 5682.5 6128.9 6246.8 6180.9 6250.8 6260.7 6267.2 6278.6 4856.8 4890.7 4939.4 4944.2 4952.6 4447.2 4448.0 4470.2 4494.2 4500.5 5653.9 5673.7 5683.5 5685.0 5693.4 6153.4 6171.1 6218.2 6220.1 6245.3 5706.4 5714.2 5720.8 5748.5 5766.8 5382.4 5387.6 5388.4 5406.6 5406.8 6209.9 6300.1 6302.7 6325.1 6347.2 5261.9 6242.4 6260.2 6280.0 6281.5 6305.2 6362.3 6372.1 6372.4 6386.1 6070.2 6111.8 6131.8 6204.1 6226.9 6255.6 6257.0 6266.6 6273.1 6300.6 5255.8 6130.0 6131.9 6149.5 6170.1 5985.1 6046.0 6053.9 6057.4 6108.9 5923.8 5961.8 5969.4 5970.7 6000.7 5104.3 6090.1 6136.0 6153.6 6154.6 4850.7 5979.2 5994.3 5998.3 6006.1 07:08:49 INFO - PROCESS | 1943 | 07:08:49 INFO - PROCESS | 1943 | [#1] ext.html Cycles:180 Average:67087.05 Median:18574.08 stddev:142767.14 (768.6%) stddev-sans-first:143080.13 07:08:49 INFO - PROCESS | 1943 | Values: 507.3 527.0 563.5 594.7 597.2 30701.7 38747.6 39860.6 39886.8 39913.0 35111.8 42234.7 42996.0 43025.0 43120.0 8561.2 9392.4 9628.7 9636.8 9656.4 8681.1 11478.9 11768.5 11779.0 11782.7 11424.8 11552.7 11554.4 11558.8 11563.7 8601.0 11425.9 11444.9 11530.7 11720.4 11502.8 11605.4 11629.1 11640.2 11645.4 39349.6 39972.4 40003.4 40007.8 40017.4 3074.9 3168.4 3248.0 3407.8 4021.4 22993.3 28212.4 29236.4 29278.9 29340.4 2623.2 2643.6 2723.3 2799.8 2815.9 428404.0 439840.0 442107.0 442408.0 442825.0 64459.0 64975.7 65250.0 65262.7 65386.3 67997.0 68291.3 68369.0 68442.7 68516.0 17482.7 17670.0 17687.6 17701.8 17740.7 12144.3 12571.7 13058.9 13342.0 13431.9 19544.6 20228.8 20229.2 20255.4 20324.1 7377.3 7502.4 7663.0 8017.6 8234.4 992.7 1001.1 1003.4 1049.5 1056.7 29780.3 29845.6 30365.9 30644.0 30702.5 1089.0 1094.0 1135.6 1155.1 1185.7 14100.7 14201.9 15479.8 15811.2 16039.0 644984.0 652134.0 654212.0 655424.0 655607.0 62605.7 62631.7 63489.4 63528.4 63609.4 1356.6 1377.6 1410.4 1423.6 1462.9 25157.7 30491.4 30505.4 30589.2 31269.0 52520.0 56967.0 56979.0 57045.0 57096.0 475760.6 492347.0 493013.0 493157.0 493345.0 23884.2 24443.9 26455.2 26783.6 26919.5 43099.0 43167.1 43696.0 44121.8 44676.3 34399.0 36410.8 36910.4 37007.8 37020.0 34833.3 35483.6 35549.8 36380.1 36385.8 36548.0 36799.0 37478.1 38692.8 39316.4 31105.2 33494.7 33510.3 33697.3 33740.3 20072.9 24434.8 24949.6 25056.0 25080.5 07:08:49 INFO - PROCESS | 1943 | 07:08:49 INFO - PROCESS | 1943 | [#2] jquery.html Cycles:180 Average:33936.87 Median:21337.91 stddev:95521.85 (447.7%) stddev-sans-first:95756.62 07:08:49 INFO - PROCESS | 1943 | Values: 397.3 397.4 399.3 402.4 413.8 14420.7 19559.9 20600.1 20662.3 21312.4 16313.2 18474.5 20556.2 20592.6 21310.9 5218.0 5904.5 6079.9 6080.0 6115.4 5372.0 5972.6 6057.7 6071.9 6119.1 5882.4 6066.2 6088.7 6089.7 6091.2 6016.1 6034.2 6070.0 6092.9 6097.8 5112.0 5291.5 5292.4 5299.1 5312.8 22861.0 25001.5 25830.6 26815.2 27295.6 1928.9 2180.1 2239.1 2321.5 2375.7 9734.7 10024.1 10100.9 10215.6 10218.9 1700.1 1752.9 1754.1 1774.3 1798.5 108763.0 111602.0 112009.9 112349.4 112880.0 11565.2 11656.7 11689.6 11707.9 11905.6 15262.2 15329.6 15338.4 15344.2 15357.8 7441.5 7879.7 8219.7 8797.0 8961.7 8666.8 8817.7 8871.7 8964.8 8982.6 4617.9 4702.4 4727.1 4762.5 4779.7 1589.8 1907.7 1967.4 1969.3 1969.5 896.3 941.5 966.5 975.8 985.0 18771.5 19453.9 21229.3 21363.5 21554.5 986.8 1002.3 1004.0 1027.2 1054.5 10077.4 11318.0 11322.2 11365.5 11509.2 556247.2 565243.2 570464.0 584066.0 584171.1 121742.8 124895.0 128089.0 128704.0 132774.0 1026.3 1042.1 1080.6 1103.8 1106.2 18422.9 18648.8 18744.4 18862.7 18990.2 48929.8 49538.0 51296.5 51350.0 52276.0 73504.0 73754.0 75539.6 75979.6 76464.6 6571.3 7779.1 7889.3 8287.3 8637.0 12314.4 12428.9 12574.9 12764.7 12828.7 10909.9 11133.0 11293.5 11343.1 11363.5 10321.7 10410.2 10850.6 11211.7 11472.5 11234.0 11243.3 11416.0 11832.9 11881.5 8210.2 8341.7 8437.4 8451.0 8498.0 9870.6 10811.6 10998.0 11112.4 11129.1 07:08:49 INFO - PROCESS | 1943 | 07:08:49 INFO - PROCESS | 1943 | [#3] mootools.html Cycles:180 Average:5694.96 Median:2637.58 stddev:3206.40 (121.6%) stddev-sans-first:3199.04 07:08:49 INFO - PROCESS | 1943 | Values: 1386.5 1566.3 1572.0 1822.2 1980.2 7925.0 8581.5 8790.4 8800.9 8802.4 8408.4 8641.8 8648.7 8652.9 8661.1 2152.2 2351.9 2429.6 2437.3 2460.4 2468.6 2492.0 2492.6 2496.3 2497.3 2415.3 2460.0 2468.2 2468.8 2469.6 2142.1 2360.1 2440.9 2440.9 2441.2 2428.6 2441.3 2444.9 2448.8 2450.6 8705.8 8761.5 8763.3 8771.3 8788.4 5115.2 5341.0 5381.0 5383.0 5402.0 3502.3 3513.4 3610.0 3625.9 3627.9 1003.3 1021.5 1023.4 1027.6 1032.4 11878.3 11912.5 11924.2 11932.3 11932.8 3857.6 3895.7 4927.8 4934.3 4939.5 10012.2 10022.6 10024.5 10027.6 10033.0 5425.1 5427.1 5450.3 5459.6 5468.0 4423.3 4586.3 4760.1 4913.7 4924.5 3907.7 3937.7 4034.9 4053.9 4067.9 1342.9 1348.4 1351.3 1355.5 1362.8 726.3 892.5 925.2 936.9 946.3 5585.3 5853.6 6028.5 6048.2 6204.4 464.4 471.4 476.6 482.4 483.4 3461.3 3938.1 3984.4 4039.2 4094.7 10350.7 10863.9 10869.8 10877.8 10883.7 10488.8 10674.1 10687.5 10757.1 10820.2 8007.5 8107.4 8135.2 8166.7 8183.9 5759.0 5826.4 5860.7 5887.3 5939.1 9715.6 9940.3 9973.2 10029.0 10050.4 9751.0 10030.5 10074.7 10128.3 10136.8 7402.1 7406.6 7419.0 7473.0 7481.0 6848.3 8057.3 8180.3 8192.3 8195.2 7192.2 7199.6 7451.3 7453.4 7528.1 7137.5 7266.5 7340.2 7376.3 7479.4 6869.4 6913.7 6938.4 6944.1 6948.1 4792.8 4932.2 5010.2 5034.5 5076.5 4306.8 4930.0 5042.6 5055.8 5101.6 07:08:49 INFO - PROCESS | 1943 | 07:08:49 INFO - PROCESS | 1943 | [#4] prototype.html Cycles:180 Average:5084.69 Median:5628.70 stddev:1566.75 (27.8%) stddev-sans-first:1529.80 07:08:49 INFO - PROCESS | 1943 | Values: 320.5 323.7 329.8 332.0 337.1 5589.9 5875.5 5904.8 5920.8 5924.9 5871.4 5874.3 5878.9 5888.4 5897.0 3882.5 4018.3 4200.5 4357.0 4395.4 4227.6 4328.2 4409.6 4448.4 4479.7 4456.6 4458.7 4466.8 4495.7 4499.8 3468.8 4180.5 4426.2 4446.3 4503.7 3830.0 3832.4 3877.3 3884.8 3894.6 5533.9 5584.1 5604.9 5622.9 5650.9 4651.5 4968.6 5177.7 5182.4 5209.2 6016.6 6132.4 6167.2 6168.0 6202.0 4277.3 4292.8 4292.9 4298.9 4315.2 6235.6 6294.5 6317.6 6446.2 6647.5 4291.8 4296.7 4351.2 4353.5 4353.6 3898.6 4425.2 4426.2 4436.2 4442.2 4841.5 4932.0 5085.7 5101.8 5122.3 5419.7 5535.2 5888.7 6231.6 6241.3 1503.5 1531.7 1537.7 1542.4 1542.7 988.7 1008.4 1015.5 1018.5 1031.3 5375.5 5797.8 5824.7 5856.5 5862.6 6197.1 6350.2 6457.9 6469.5 6534.1 4869.0 4953.7 5634.5 5729.0 5862.5 2866.6 3394.3 3419.5 3426.8 3429.3 6473.2 6758.6 6820.4 6831.6 6838.8 5914.2 6254.6 6366.2 6675.7 6683.5 6552.3 6586.7 6809.6 6812.9 6822.3 5293.9 5871.6 6459.8 6468.4 6476.2 5159.8 5450.3 6373.8 6420.7 6434.6 5602.2 5831.8 6535.2 6654.9 6859.9 4328.7 4355.0 4362.7 4368.0 4381.0 6231.0 6497.0 6499.5 6500.0 6511.2 5793.3 6284.2 6344.1 6354.0 6359.8 6186.2 6291.6 6305.5 6327.6 6328.9 6157.6 6164.6 6167.9 6168.7 6184.3 5557.8 6072.9 6300.3 6317.5 6344.9 5288.6 5330.6 5840.6 5862.7 6046.8 07:08:49 INFO - PROCESS | 1943 | 07:08:49 INFO - PROCESS | 1943 | [#5] yui.html Cycles:180 Average:18371.18 Median:598.24 stddev:26605.71 (4447.3%) stddev-sans-first:26646.82 07:08:49 INFO - PROCESS | 1943 | Values: 583.6 669.4 674.4 745.1 802.8 12618.5 15020.2 15027.5 15223.4 15345.8 13999.1 14062.2 14077.8 14379.5 15691.0 922.7 933.1 934.9 936.6 936.8 910.8 933.6 933.7 936.7 937.0 979.2 980.3 981.6 981.9 984.2 888.6 896.0 976.4 976.8 978.5 730.0 732.1 732.4 732.8 733.2 14765.9 14809.3 14877.9 14888.6 14901.9 12617.6 12690.9 12710.6 12851.0 13455.5 10032.2 10091.6 10257.4 10349.4 10519.9 3236.9 3417.9 3480.2 3546.3 3731.9 78202.0 78851.0 79809.0 79891.0 80699.8 9307.2 9537.4 9750.7 9788.8 9855.1 21745.7 21751.7 21770.5 21793.7 21799.0 7468.0 7632.8 7701.5 7705.0 7768.0 4044.7 4098.1 4105.5 4170.3 4208.6 3412.3 3484.1 3484.2 3491.3 3492.5 581.9 619.4 626.6 648.4 659.9 556.3 587.8 596.4 598.4 603.8 6583.4 7447.7 7728.4 7875.6 7985.6 543.7 570.9 575.8 598.1 617.7 3634.8 3803.5 3842.7 4006.6 4118.1 100906.5 101023.5 101825.0 102297.0 103901.0 80099.0 80455.6 80487.8 80641.0 82009.0 67112.3 72605.0 72718.3 74003.6 74194.0 7596.2 7629.4 7713.1 7751.5 7767.5 45346.0 45990.0 46165.1 46165.4 47543.8 63080.7 66681.3 67886.0 67915.7 68301.9 9875.7 9916.8 9937.0 10173.5 10535.6 11028.2 11034.6 11352.9 11435.1 11503.0 10325.1 10370.2 10555.8 10753.9 10756.8 10357.9 10366.4 10767.9 10785.4 10885.8 10223.9 10387.7 10487.2 10647.7 10829.5 8723.7 9002.2 9035.1 9104.5 9241.4 7212.4 8835.5 8920.5 8999.0 9048.4 07:08:49 INFO - PROCESS | 1943 | -------- Summary: end -------- 07:08:49 INFO - PROCESS | 1943 | 07:08:50 INFO - TEST-INFO | 1943: exit 0 07:08:50 INFO - TEST-OK | dromaeo_css | took 1114836ms 07:08:50 INFO - TEST-START | kraken 07:08:50 INFO - operating with platform_type : mac_ 07:08:50 INFO - Initialising browser for kraken test... 07:08:50 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpQEZWpA/profile http://localhost:52278/getInfo.html) 07:08:52 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 07:08:52 INFO - PROCESS | 1946 | colorDepth:24 07:08:52 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 07:08:52 INFO - PROCESS | 1946 | __metrics 07:08:52 INFO - PROCESS | 1946 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:08:52 ERROR - PROCESS | 1946 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:08:52 INFO - TEST-INFO | 1946: exit 0 07:08:52 INFO - Browser initialized. 07:08:52 INFO - Running cycle 1/1 for kraken test... 07:08:52 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpQEZWpA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:09:04 INFO - PROCESS | 1948 | RSS: Main: 239308800 07:09:04 INFO - PROCESS | 1948 | 07:09:07 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52278/tests/kraken/driver.html?testName=audio-beat-detection) 07:09:08 INFO - PROCESS | 1948 | RSS: Main: 287346688 07:09:08 INFO - PROCESS | 1948 | 07:09:11 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52278/tests/kraken/driver.html?testName=audio-dft) 07:09:11 INFO - PROCESS | 1948 | RSS: Main: 300154880 07:09:11 INFO - PROCESS | 1948 | 07:09:15 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52278/tests/kraken/driver.html?testName=audio-fft) 07:09:15 INFO - PROCESS | 1948 | RSS: Main: 390270976 07:09:15 INFO - PROCESS | 1948 | 07:09:17 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52278/tests/kraken/driver.html?testName=audio-oscillator) 07:09:18 INFO - PROCESS | 1948 | RSS: Main: 289349632 07:09:18 INFO - PROCESS | 1948 | 07:09:20 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:09:21 INFO - PROCESS | 1948 | RSS: Main: 294350848 07:09:21 INFO - PROCESS | 1948 | 07:09:23 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52278/tests/kraken/driver.html?testName=imaging-darkroom) 07:09:24 INFO - PROCESS | 1948 | RSS: Main: 329986048 07:09:24 INFO - PROCESS | 1948 | 07:09:27 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52278/tests/kraken/driver.html?testName=imaging-desaturate) 07:09:28 INFO - PROCESS | 1948 | RSS: Main: 332435456 07:09:28 INFO - PROCESS | 1948 | 07:09:30 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52278/tests/kraken/driver.html?testName=json-parse-financial) 07:09:31 INFO - PROCESS | 1948 | RSS: Main: 329138176 07:09:31 INFO - PROCESS | 1948 | 07:09:33 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:09:33 INFO - PROCESS | 1948 | RSS: Main: 321802240 07:09:33 INFO - PROCESS | 1948 | 07:09:35 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52278/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:09:36 INFO - PROCESS | 1948 | RSS: Main: 328876032 07:09:36 INFO - PROCESS | 1948 | 07:09:38 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:09:39 INFO - PROCESS | 1948 | RSS: Main: 330723328 07:09:39 INFO - PROCESS | 1948 | 07:09:41 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:09:42 INFO - PROCESS | 1948 | RSS: Main: 335552512 07:09:42 INFO - PROCESS | 1948 | 07:09:45 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:09:45 INFO - PROCESS | 1948 | RSS: Main: 333307904 07:09:45 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52278/tests/kraken/driver.html?testName=ai-astar) 07:09:47 INFO - PROCESS | 1948 | RSS: Main: 397832192 07:09:47 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | __start_tp_report 07:09:47 INFO - PROCESS | 1948 | _x_x_mozilla_page_load 07:09:47 INFO - PROCESS | 1948 | _x_x_mozilla_page_load_details 07:09:47 INFO - PROCESS | 1948 | |i|pagename|runs| 07:09:47 INFO - PROCESS | 1948 | |0;ai-astar;112;113;108;112;111;112;111;113;105;112 07:09:47 INFO - PROCESS | 1948 | |1;audio-beat-detection;159;124;145;173;120;143;141;121;174;118 07:09:47 INFO - PROCESS | 1948 | |2;audio-dft;185;184;183;183;184;187;181;188;182;184 07:09:47 INFO - PROCESS | 1948 | |3;audio-fft;76;73;91;93;66;59;56;82;86;56 07:09:47 INFO - PROCESS | 1948 | |4;audio-oscillator;96;99;132;77;80;78;121;80;79;122 07:09:47 INFO - PROCESS | 1948 | |5;imaging-gaussian-blur;86;122;85;82;86;83;86;83;86;82 07:09:47 INFO - PROCESS | 1948 | |6;imaging-darkroom;92;92;92;91;100;141;93;85;148;141 07:09:47 INFO - PROCESS | 1948 | |7;imaging-desaturate;87;84;86;84;83;84;85;84;87;85 07:09:47 INFO - PROCESS | 1948 | |8;json-parse-financial;60;61;60;60;59;59;59;79;57;58 07:09:47 INFO - PROCESS | 1948 | |9;json-stringify-tinderbox;64;67;72;73;70;67;66;67;68;68 07:09:47 INFO - PROCESS | 1948 | |10;stanford-crypto-aes;83;82;82;82;81;82;81;81;83;84 07:09:47 INFO - PROCESS | 1948 | |11;stanford-crypto-ccm;140;139;139;137;136;137;139;137;181;135 07:09:47 INFO - PROCESS | 1948 | |12;stanford-crypto-pbkdf2;169;173;170;168;170;175;176;168;171;172 07:09:47 INFO - PROCESS | 1948 | |13;stanford-crypto-sha256-iterative;68;70;69;69;70;68;69;70;75;97 07:09:47 INFO - PROCESS | 1948 | __end_tp_report 07:09:47 INFO - PROCESS | 1948 | __start_cc_report 07:09:47 INFO - PROCESS | 1948 | _x_x_mozilla_cycle_collect,1997 07:09:47 INFO - PROCESS | 1948 | __end_cc_report 07:09:47 INFO - PROCESS | 1948 | __startTimestamp1461420587964__endTimestamp 07:09:47 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | ------- Summary: start ------- 07:09:47 INFO - PROCESS | 1948 | Number of tests: 14 07:09:47 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:2.51 (2.2%) stddev-sans-first:2.64 07:09:47 INFO - PROCESS | 1948 | Values: 112.0 113.0 108.0 112.0 111.0 112.0 111.0 113.0 105.0 112.0 07:09:47 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | [#1] audio-beat-detection Cycles:10 Average:141.80 Median:144.00 stddev:21.37 (14.8%) stddev-sans-first:21.74 07:09:47 INFO - PROCESS | 1948 | Values: 159.0 124.0 145.0 173.0 120.0 143.0 141.0 121.0 174.0 118.0 07:09:47 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.00 stddev:2.13 (1.2%) stddev-sans-first:2.24 07:09:47 INFO - PROCESS | 1948 | Values: 185.0 184.0 183.0 183.0 184.0 187.0 181.0 188.0 182.0 184.0 07:09:47 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | [#3] audio-fft Cycles:10 Average:73.80 Median:79.00 stddev:14.14 (17.9%) stddev-sans-first:14.98 07:09:47 INFO - PROCESS | 1948 | Values: 76.0 73.0 91.0 93.0 66.0 59.0 56.0 82.0 86.0 56.0 07:09:47 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | [#4] audio-oscillator Cycles:10 Average:96.40 Median:79.50 stddev:21.32 (26.8%) stddev-sans-first:22.61 07:09:47 INFO - PROCESS | 1948 | Values: 96.0 99.0 132.0 77.0 80.0 78.0 121.0 80.0 79.0 122.0 07:09:47 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | [#5] imaging-gaussian-blur Cycles:10 Average:88.10 Median:85.50 stddev:12.03 (14.1%) stddev-sans-first:12.74 07:09:47 INFO - PROCESS | 1948 | Values: 86.0 122.0 85.0 82.0 86.0 83.0 86.0 83.0 86.0 82.0 07:09:47 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | [#6] imaging-darkroom Cycles:10 Average:107.50 Median:91.50 stddev:25.06 (27.4%) stddev-sans-first:25.94 07:09:47 INFO - PROCESS | 1948 | Values: 92.0 92.0 92.0 91.0 100.0 141.0 93.0 85.0 148.0 141.0 07:09:47 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.00 stddev:1.37 (1.6%) stddev-sans-first:1.22 07:09:47 INFO - PROCESS | 1948 | Values: 87.0 84.0 86.0 84.0 83.0 84.0 85.0 84.0 87.0 85.0 07:09:47 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | [#8] json-parse-financial Cycles:10 Average:61.20 Median:60.00 stddev:6.36 (10.6%) stddev-sans-first:6.73 07:09:47 INFO - PROCESS | 1948 | Values: 60.0 61.0 60.0 60.0 59.0 59.0 59.0 79.0 57.0 58.0 07:09:47 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:68.00 stddev:2.74 (4.0%) stddev-sans-first:2.45 07:09:47 INFO - PROCESS | 1948 | Values: 64.0 67.0 72.0 73.0 70.0 67.0 66.0 67.0 68.0 68.0 07:09:47 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:82.00 stddev:0.99 (1.2%) stddev-sans-first:1.00 07:09:47 INFO - PROCESS | 1948 | Values: 83.0 82.0 82.0 82.0 81.0 82.0 81.0 81.0 83.0 84.0 07:09:47 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | [#11] stanford-crypto-ccm Cycles:10 Average:142.00 Median:139.00 stddev:13.79 (9.9%) stddev-sans-first:14.61 07:09:47 INFO - PROCESS | 1948 | Values: 140.0 139.0 139.0 137.0 136.0 137.0 139.0 137.0 181.0 135.0 07:09:47 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.20 Median:171.50 stddev:2.78 (1.6%) stddev-sans-first:2.83 07:09:47 INFO - PROCESS | 1948 | Values: 169.0 173.0 170.0 168.0 170.0 175.0 176.0 168.0 171.0 172.0 07:09:47 INFO - PROCESS | 1948 | 07:09:47 INFO - PROCESS | 1948 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:70.00 stddev:8.83 (12.6%) stddev-sans-first:9.22 07:09:47 INFO - PROCESS | 1948 | Values: 68.0 70.0 69.0 69.0 70.0 68.0 69.0 70.0 75.0 97.0 07:09:47 INFO - PROCESS | 1948 | -------- Summary: end -------- 07:09:47 INFO - PROCESS | 1948 | 07:09:48 INFO - TEST-INFO | 1948: exit 0 07:09:48 INFO - TEST-OK | kraken | took 58550ms 07:09:48 INFO - SUITE-END | took 1173s 07:09:49 INFO - Completed test suite (00:19:33) 07:09:49 INFO - javascript benchmark 07:09:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3318.9170270743234, 3418.2821607462447, 3448.0690213642333, 3449.8620055197794, 3489.6859282664554, 5639.548836093113, 5737.856553586163, 5745.827625171251, 5783.21926539917, 5806.73869675864, 5704.714764261791, 5763.769449222026, 5883.264591926009, 5927.2294601701715, 5930.199423077894, 5137.5633071188995, 5156.458571849958, 5190.4031036430915, 5190.636655434121, 5269.920951185735, 5331.253624492555, 5351.759170837302, 5367.597430192732, 5386.165144402624, 5389.973050134744, 5124.103281925648, 5200.635955483118, 5218.086834803894, 5222.138347172712, 5313.840584782462, 5188.507091826271, 5190.97404512975, 5237.554807841338, 5247.291615631879, 5299.788008479694, 5051.873703157414, 5154.53609175176, 5182.870428239286, 5202.271681964528, 5275.393329767087, 5496.890062198733, 5631.8592035199035, 5636.408177141423, 5639.238702775114, 5640.689762063085, 6126.181801870944, 6216.7824126155365, 6224.751009959597, 6235.345288744707, 6238.719257633417, 5448.91826622601, 6217.191765070512, 6222.66736743981, 6284.968575157141, 6289.748410063592, 5675.177099320658, 5760.193572899755, 5773.595848290579, 5775.942240577624, 5776.797812076557, 5076.568491678248, 5436.891262174734, 6186.1648677428875, 6222.439980401786, 6311.116443697886, 5353.197020446886, 6018.15745795589, 6169.629822210682, 6184.319724830265, 6188.4739797116845, 6121.296050954108, 6182.258129024546, 6207.937920620826, 6270.0, 6273.686315684197, 5171.63798534099, 5480.972595136999, 5682.488576028178, 6128.869808884179, 6246.781362652377, 6180.907286390708, 6250.812475625738, 6260.718267677966, 6267.185265915493, 6278.560500764994, 4856.8300109495995, 4890.657653964188, 4939.357883475125, 4944.233643785219, 4952.603791696655, 4447.2439685253585, 4448.0, 4470.217711900469, 4494.16857881293, 4500.459944806644, 5653.886922261532, 5673.687947162945, 5683.516901063374, 5685.005124103265, 5693.430656934356, 6153.384661533901, 6171.13604095427, 6218.160548326008, 6220.066989951543, 6245.281792593819, 5706.42935706426, 5714.228579141846, 5720.799772007959, 5748.511376532958, 5766.769329226868, 5382.434844341366, 5387.6228663993925, 5388.380336261302, 5406.621536492407, 5406.756695948692, 6209.9068513972325, 6300.086487459384, 6302.747890084345, 6325.05124231369, 6347.206599175103, 5261.868453288545, 6242.375762423721, 6260.154879091265, 6280.026595877646, 6281.4660753834605, 6305.234534330395, 6362.300146983921, 6372.07604897278, 6372.36276372359, 6386.074019267087, 6070.2108725865355, 6111.804454079419, 6131.774283661862, 6204.069389591597, 6226.937730622636, 6255.593386429868, 6257.0, 6266.561340706106, 6273.090401891609, 6300.59046161998, 5255.816046438356, 6130.01919692847, 6131.908021379772, 6149.477294430024, 6170.074488826712, 5985.102234664835, 6046.032634778414, 6053.939460605514, 6057.42454466825, 6108.918862504732, 5923.822285331447, 5961.7615295387695, 5969.403059693996, 5970.731317090828, 6000.669963152069, 5104.285400043923, 6090.056041313604, 6136.018237082046, 6153.569250152446, 6154.599951003171, 4850.708957462563, 5979.192808970907, 5994.28068631767, 5998.310194327708, 6006.099085137264], "value": 5663.044090973822, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.29232720355105, 527.0434161995977, 563.4506356302608, 594.7115648910278, 597.1818608506347, 30701.692983070163, 38747.612523874785, 39860.60139398605, 39886.800565997204, 39913.00217494564, 35111.82444087783, 42234.73295801131, 42996.0, 43025.0, 43120.0, 8561.1866872647, 9392.436453812776, 9628.71113866585, 9636.759081022961, 9656.420614763138, 8681.1318868113, 11478.942605287004, 11768.52925882968, 11778.998785103277, 11782.705432364217, 11424.771504569904, 11552.653420397359, 11554.422278886022, 11558.768824623545, 11563.710907227345, 8600.956995214983, 11425.942870285595, 11444.942775286112, 11530.654080377602, 11720.355380454072, 11502.827457588142, 11605.419729013516, 11629.127815413878, 11640.195769655717, 11645.359505227209, 39349.60650393488, 39972.40041399352, 40003.39994900079, 40007.79996100001, 40017.39973900394, 3074.9231269218226, 3168.350488149924, 3248.025592322294, 3407.8296085195643, 4021.4369988201674, 22993.310200694006, 28212.43575128486, 29236.41527169466, 29278.853605731834, 29340.413191736257, 2623.2130360891847, 2643.550596398617, 2723.319170207448, 2799.8040137190405, 2815.85920703964, 428404.0, 439840.0, 442107.0, 442408.0, 442825.0, 64459.03311450332, 64975.67512162409, 65250.02124968129, 65262.673686631264, 65386.346136538974, 67997.0, 68291.3170868285, 68368.97446538306, 68442.65778671174, 68516.0, 17482.65034699299, 17670.0, 17687.557811054827, 17701.822981770274, 17740.733888991675, 12144.271343719405, 12571.685707857378, 13058.869411305765, 13342.0, 13431.93284033593, 19544.63368154282, 20228.797712022984, 20229.190832366836, 20255.392338229878, 20324.085416156307, 7377.299156580117, 7502.399808015346, 7662.96168519165, 8017.639206235734, 8234.447321330286, 992.7121134870806, 1001.0790073132736, 1003.4481035430484, 1049.5434485998614, 1056.7252514346171, 29780.2554936124, 29845.552316715268, 30365.937192198166, 30644.0, 30702.53946190809, 1088.983665245022, 1094.0, 1135.6252436696036, 1155.1047937848066, 1185.7213554814612, 14100.717985640435, 14201.934854885925, 15479.767803482957, 15811.209439527978, 16039.04812711573, 644984.0, 652134.0, 654212.0, 655424.0, 655607.0, 62605.68697156576, 62631.6868415655, 63489.36510634927, 63528.36471635317, 63609.36390636034, 1356.593022093368, 1377.6280404290985, 1410.400579753621, 1423.6156237815733, 1462.8975971682094, 25157.748422516273, 30491.390172196876, 30505.389892201587, 30589.23526911845, 31269.0, 52520.0, 56967.0, 56979.0, 57045.0, 57096.0, 475760.6211969057, 492347.0, 493013.0, 493157.0, 493345.0, 23884.164054258017, 24443.877780610983, 26455.206343809718, 26783.598246025937, 26919.461610767285, 43099.0, 43167.13665726731, 43696.0, 44121.77939110284, 44676.32985505155, 34398.96803095911, 36410.8179459101, 36910.446343304335, 37007.814960925025, 37020.0, 34833.30333393368, 35483.64516354907, 35549.822250889614, 36380.090497737765, 36385.818070909474, 36548.0, 36799.0, 37478.06304842401, 38692.8392148236, 39316.41025384564, 31105.222369440944, 33494.66505335013, 33510.32979340448, 33697.326053478304, 33740.325193495504, 20072.89963550173, 24434.755652443248, 24949.625755613317, 25056.0, 25080.498390032462], "value": 20226.164715872936, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.3245482679444, 397.37413573621546, 399.3191608307835, 402.4164960806831, 413.80344336440174, 14420.711585768304, 19559.902200489014, 20600.072996715164, 20662.276820311294, 21312.36062918115, 16313.184340782973, 18474.538136546595, 20556.177752889867, 20592.588148237064, 21310.89344553275, 5218.0, 5904.468597826206, 6079.939200607982, 6079.969600151993, 6115.449609535141, 5372.0, 5972.641641501502, 6057.697115144248, 6071.908921366183, 6119.082137679361, 5882.3529411764885, 6066.150738896558, 6088.726007329658, 6089.725962331706, 6091.208142941411, 6016.067509536029, 6034.24571928509, 6069.969650151766, 6092.939070609325, 6097.817065488065, 5111.974440127776, 5291.523762861322, 5292.391374991848, 5299.125644268691, 5312.76092575835, 22861.0, 25001.4999700005, 25830.612540811904, 26815.19554413371, 27295.590566141524, 1928.855335849803, 2180.1279488204686, 2239.1267405711787, 2321.454458202321, 2375.693368647237, 9734.707958761248, 10024.147947424477, 10100.898991010143, 10215.642452514126, 10218.897811021869, 1700.0649642696467, 1752.8948263104255, 1754.0615770562688, 1774.343706904702, 1798.4784412520353, 108763.0, 111602.0, 112009.87990120077, 112349.4382528082, 112880.0, 11565.183489448462, 11656.70858228534, 11689.649310520697, 11707.88292117068, 11905.642830715093, 15262.236888155549, 15329.6167595811, 15338.386464541294, 15344.158700955957, 15357.769633455284, 7441.479096463196, 7879.684812607432, 8219.712310069119, 8797.032326444087, 8961.731148065439, 8666.795445202251, 8817.691380801642, 8871.689490867917, 8964.820703585892, 8982.550872456351, 4617.861464156013, 4702.388689470347, 4727.101850648366, 4762.523747625279, 4779.689320194177, 1589.8235305873802, 1907.6947688369794, 1967.390109066194, 1969.3402710091993, 1969.4682435742557, 896.3008853094597, 941.5292353823088, 966.4587830814626, 975.8048390321964, 984.9753756156153, 18771.530711732317, 19453.902730486257, 21229.256976006076, 21363.46591335229, 21554.461138471663, 986.8075725233654, 1002.2783595811004, 1003.9799204015878, 1027.1525991057408, 1054.5360041581832, 10077.445740484343, 11318.0, 11322.150838686966, 11365.545378184945, 11509.194356394972, 556247.2187639116, 565243.1737841284, 570464.0, 584066.0, 584171.0791446101, 121742.78257217491, 124895.0, 128089.0, 128704.0, 132774.0, 1026.312370711625, 1042.0673497220098, 1080.5623722392443, 1103.823388257875, 1106.2145876427826, 18422.90788546049, 18648.81351186525, 18744.437666870017, 18862.717059244398, 18990.24039038423, 48929.755351223015, 49538.0, 51296.48703513067, 51350.0, 52276.0, 73504.0, 73754.0, 75539.62230188814, 75979.62010189914, 76464.61767691126, 6571.342865713582, 7779.105402878741, 7889.278199021329, 8287.337013038821, 8637.049924508174, 12314.384280786104, 12428.875711243134, 12574.937125314314, 12764.680882978, 12828.743425131632, 10909.945450272699, 11133.0, 11293.491792869176, 11343.149263805082, 11363.488643010918, 10321.74195645115, 10410.219233557362, 10850.620228291971, 11211.66365009051, 11472.541098355972, 11234.0, 11243.325400475998, 11416.029637480908, 11832.881671183179, 11881.465334060162, 8210.220029097227, 8341.708040218564, 8437.367197460339, 8451.028131764926, 8497.983215958246, 9870.605175793175, 10811.6215932442, 10998.010179083922, 11112.388818615054, 11129.109671226444], "value": 9565.322717657145, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.5008596905107, 1566.2716836670945, 1572.0017788704174, 1822.1709122349332, 1980.2178139634855, 7924.960375198123, 8581.485110893344, 8790.38467307288, 8800.91199088009, 8802.383833131684, 8408.369372297071, 8641.783955401119, 8648.65405383784, 8652.91347086529, 8661.133886611336, 2152.2467136502178, 2351.9176828810996, 2429.5626787178303, 2437.341917682225, 2460.4095017195923, 2468.6173643085262, 2491.950160996779, 2492.5887228607257, 2496.325991982164, 2497.325722055053, 2415.311636183688, 2460.0, 2468.1978357034036, 2468.7654672806057, 2469.5678256305164, 2142.1110239250693, 2360.091364824538, 2440.890159942798, 2440.9267721968367, 2441.1578005588044, 2428.6357046443, 2441.279822452381, 2444.877756112205, 2448.7673671001226, 2450.5711500487523, 8705.825883482295, 8761.474311541328, 8763.298936085162, 8771.342149338825, 8788.384813063089, 5115.181570948667, 5341.03861304965, 5381.0, 5383.031054410205, 5401.9736250112355, 3502.334556434295, 3513.3675938331344, 3610.0, 3625.8730944417084, 3627.9455808162897, 1003.2676146413087, 1021.5300961557692, 1023.3859684189501, 1027.6300531808542, 1032.3702541449709, 11878.287302761775, 11912.463939122761, 11924.224925379822, 11932.343721095336, 11932.821007684892, 3857.5949525300002, 3895.7467764595212, 4927.802887884516, 4934.3338649282605, 4939.506049395031, 10012.199024078056, 10022.599096036078, 10024.54889529973, 10027.649032283834, 10033.0, 5425.077736784756, 5427.1316589345515, 5450.264214331015, 5459.590530710133, 5468.043092458804, 4423.336499525032, 4586.2661974083985, 4760.071786001697, 4913.70517768935, 4924.482929292444, 3907.7264591478324, 3937.7440466369594, 4034.899127521777, 4053.8986525337104, 4067.8576249831112, 1342.90599658023, 1348.4471366739785, 1351.3108314759543, 1355.5391167003265, 1362.8092067110613, 726.2773540327303, 892.4912799704236, 925.2366797392178, 936.8663916660917, 946.3030363464072, 5585.273914391103, 5853.590248682636, 6028.547858910511, 6048.213732214784, 6204.410580994799, 464.3638215644589, 471.3824889394904, 476.6187050359698, 482.39700374531833, 483.4174819342684, 3461.2558299965626, 3938.0942383252013, 3984.362501999666, 4039.2325458162854, 4094.733842300241, 10350.689479315633, 10863.891361086446, 10869.83695244572, 10877.782444351069, 10883.67348979532, 10488.842667359995, 10674.146068314516, 10687.465626718633, 10757.139428845674, 10820.188485863591, 8007.519548827089, 8107.351411887035, 8135.227153420416, 8166.714165004197, 8183.918160818315, 5759.0, 5826.41735826414, 5860.677662728591, 5887.293524777054, 5939.138824870283, 9715.611375544899, 9940.254480913816, 9973.202143828621, 10028.997100289913, 10050.39697618116, 9750.951245243728, 10030.548625311732, 10074.69775906724, 10128.34165779222, 10136.847947280945, 7402.148752893486, 7406.592637404994, 7419.0, 7473.0, 7481.0, 6848.280930502226, 8057.315128214167, 8180.263776260165, 8192.34461243111, 8195.221453961864, 7192.244814294633, 7199.568025918462, 7451.2921272479025, 7453.440991925519, 7528.096628404626, 7137.536060156073, 7266.455015873937, 7340.192578816219, 7376.299251571092, 7479.439042071757, 6869.381755642016, 6913.654317284015, 6938.375546200866, 6944.062551555476, 6948.062011628567, 4792.760361981817, 4932.161532539405, 5010.173321401928, 5034.496550344937, 5076.466332360294, 4306.784660767012, 4930.0, 5042.647014708935, 5055.823046193365, 5101.566366858859], "value": 4516.271016380532, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.4920201480653, 323.6633900743227, 329.7856393344327, 332.04868053028065, 337.1369294605811, 5589.888202235953, 5875.5005824504915, 5904.793332233373, 5920.822375328737, 5924.911126333098, 5871.354151043392, 5874.265716785402, 5878.884191378565, 5888.44059814318, 5896.970515147429, 3882.524640636554, 4018.276710192164, 4200.453940987667, 4356.969802932291, 4395.384646149541, 4227.619514243717, 4328.2209202343565, 4409.581089796464, 4448.354988526662, 4479.731216127043, 4456.59890609845, 4458.732476051431, 4466.82132714691, 4495.685302028843, 4499.820007199708, 3468.7745296555645, 4180.540140584533, 4426.18115648606, 4446.288593825005, 4503.707259028169, 3829.9808500957597, 3832.405977073558, 3877.2827027000094, 3884.844606215748, 3894.6300101490597, 5533.94854977557, 5584.106542953149, 5604.887902241932, 5622.943770562283, 5650.90916360945, 4651.534846515356, 4968.608789538935, 5177.689338639656, 5182.352205974254, 5209.2446595243855, 6016.639001659914, 6132.386761323876, 6167.198264225622, 6168.0, 6202.0, 4277.294246449332, 4292.763897985641, 4292.871213863589, 4298.935515967263, 4315.223259813232, 6235.625862448268, 6294.527910406738, 6317.6209427434505, 6446.161998940154, 6647.501437392167, 4291.785410729451, 4296.720713153635, 4351.216780979391, 4353.521112677603, 4353.6081752641785, 3898.6159913230395, 4425.247707889666, 4426.18115648606, 4436.179306828247, 4442.244818380818, 4841.540053694894, 4931.964287499593, 5085.720285384339, 5101.770420331094, 5122.334096567422, 5419.729013549307, 5535.169724541271, 5888.704485013291, 6231.626102433868, 6241.285041007176, 1503.5414198669291, 1531.663034132489, 1537.6617144228253, 1542.383046781274, 1542.691461707663, 988.7083310423442, 1008.3949630221955, 1015.5277795824977, 1018.4805749067881, 1031.3038698878227, 5375.532781356003, 5797.797077102281, 5824.650520968671, 5856.531477481792, 5862.648241105548, 6197.101420294032, 6350.237971443364, 6457.903131453032, 6469.45009674183, 6534.117894084333, 4868.95131048692, 4953.678010929278, 5634.464725851038, 5728.977586369844, 5862.472377485961, 2866.584345269924, 3394.321135772806, 3419.5212670226188, 3426.794392336418, 3429.3141371725756, 6473.190851143607, 6758.594484330956, 6820.35206655367, 6831.64308313631, 6838.760643377557, 5914.2498738087, 6254.6247225166635, 6366.172397588284, 6675.659472422043, 6683.460285091634, 6552.312007239267, 6586.70359833799, 6809.604031173598, 6812.863742725118, 6822.317768223138, 5293.947060529345, 5871.588988770745, 6459.773907913201, 6468.353164683494, 6476.158099447041, 5159.767810448464, 5450.31871016123, 6373.840653983688, 6420.683759829317, 6434.581752186093, 5602.159676048625, 5831.804480081658, 6535.202819224561, 6654.90017649726, 6859.931400686129, 4328.696991210585, 4354.9782251088545, 4362.694611377173, 4367.956320436755, 4380.9780951095045, 6230.968845155927, 6497.0, 6499.512536559682, 6499.967500162469, 6511.21865376139, 5793.304803423616, 6284.151639528605, 6344.080108384166, 6353.951597986281, 6359.777407790705, 6186.226721659793, 6291.559590828598, 6305.495560355253, 6327.55707100517, 6328.936710632835, 6157.599756015845, 6164.630122192682, 6167.938320616916, 6168.691565421621, 6184.257889053342, 5557.833265002057, 6072.906876762048, 6300.306966233802, 6317.4630156437215, 6344.873102537831, 5288.550473209821, 5330.600204984564, 5840.649561026352, 5862.736176872137, 6046.818595442144], "value": 4582.084907939723, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.5856541855283, 669.4134900286319, 674.3896773420057, 745.1133151549656, 802.7993001749562, 12618.495260189582, 15020.24898755062, 15027.549173524798, 15223.391064357413, 15345.846541534582, 13999.090059146149, 14062.15627062377, 14077.788833167511, 14379.49671761486, 15690.980086294383, 922.6540047482194, 933.1135421349745, 934.8971613122551, 936.5551363102505, 936.8313703533395, 910.836049511091, 933.6405483888718, 933.6545478173085, 936.7377134402378, 936.9880529028633, 979.211734553684, 980.2599037726507, 981.5779214937573, 981.906718861707, 984.2322988069318, 888.564603344363, 895.9641614335419, 976.4092723902008, 976.8046390721884, 978.5351957820014, 729.9707412548281, 732.1068296678044, 732.3591857125016, 732.7618523979675, 733.2044731466366, 14765.852341476555, 14809.259537023214, 14877.925610371878, 14888.62778430537, 14901.850981490155, 12617.621471355875, 12690.87309126906, 12710.618681439571, 12851.0, 13455.461781528727, 10032.197424206046, 10091.596336146546, 10257.384556926609, 10349.379037257713, 10519.947400263027, 3236.8543415546355, 3417.8974630761118, 3480.1647604574973, 3546.2552863898613, 3731.8880433586764, 78202.0, 78850.99999999943, 79809.00477488051, 79891.01362329708, 80699.7895031569, 9307.20888724466, 9537.427754334762, 9750.658726944523, 9788.804223915624, 9855.063768941938, 21745.67381489279, 21751.67372489414, 21770.455738606663, 21793.67309490359, 21799.0, 7468.0, 7632.771016869503, 7701.537907725442, 7705.0, 7768.028996375453, 4044.716869819143, 4098.139390727921, 4105.486814148231, 4170.311898536768, 4208.6366809614165, 3412.3175364927115, 3484.1289677580603, 3484.181217413906, 3491.30173965208, 3492.511048453219, 581.8798812286332, 619.3527763487167, 626.6459450410504, 648.4275631593367, 659.868026394723, 556.2546188107955, 587.8412828536271, 596.3618927747269, 598.374698440131, 603.7916918663054, 6583.446052178397, 7447.664855081534, 7728.420368472274, 7875.645595948196, 7985.560794156261, 543.6602123672704, 570.9314882214161, 575.789836709598, 598.1088178613921, 617.7312868901952, 3634.83643236055, 3803.5245594300713, 3842.6733727633464, 4006.599340066028, 4118.135191609736, 100906.4954675222, 101023.49488252512, 101825.0, 102297.0, 103901.0, 80099.0, 80455.59772201102, 80487.7926831098, 80641.0, 82009.0, 67112.32887671159, 72605.0, 72718.27281727221, 74003.62998184975, 74194.0, 7596.164421913474, 7629.4413219202, 7713.074431068083, 7751.534907905544, 7767.495112817649, 45346.0, 45990.0, 46165.07596854305, 46165.38421155243, 47543.76228118837, 63080.73838523112, 66681.33296667428, 67886.0, 67915.66042169757, 68301.87735919727, 9875.703728888146, 9916.752081198028, 9936.950315248667, 10173.489462286778, 10535.63125290611, 11028.22802403824, 11034.558617655204, 11352.886471135513, 11435.142364322875, 11503.0, 10325.070743633109, 10370.170386369224, 10555.780486951246, 10753.89246107529, 10756.784864302826, 10357.896421035995, 10366.429846358222, 10767.946160269148, 10785.366338602309, 10885.836712449161, 10223.897761022294, 10387.68836934893, 10487.213458990454, 10647.733806654896, 10829.512671929624, 8723.65105395777, 9002.234810040958, 9035.096490350912, 9104.499252541174, 9241.353105282566, 7212.386947109554, 8835.514046727492, 8920.509371984603, 8999.0, 9048.41185322952], "value": 6813.522168500412, "unit": "score"}], "name": "dromaeo_css", "value": 7325.20984199148, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 108.0, 112.0, 111.0, 112.0, 111.0, 113.0, 105.0, 112.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 124.0, 145.0, 173.0, 120.0, 143.0, 141.0, 121.0, 174.0, 118.0], "value": 141.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 183.0, 183.0, 184.0, 187.0, 181.0, 188.0, 182.0, 184.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 91.0, 93.0, 66.0, 59.0, 56.0, 82.0, 86.0, 56.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 99.0, 132.0, 77.0, 80.0, 78.0, 121.0, 80.0, 79.0, 122.0], "value": 96.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 122.0, 85.0, 82.0, 86.0, 83.0, 86.0, 83.0, 86.0, 82.0], "value": 88.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 91.0, 100.0, 141.0, 93.0, 85.0, 148.0, 141.0], "value": 107.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 84.0, 83.0, 84.0, 85.0, 84.0, 87.0, 85.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 60.0, 59.0, 59.0, 59.0, 79.0, 57.0, 58.0], "value": 61.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 72.0, 73.0, 70.0, 67.0, 66.0, 67.0, 68.0, 68.0], "value": 68.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 82.0, 82.0, 81.0, 82.0, 81.0, 81.0, 83.0, 84.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 139.0, 137.0, 136.0, 137.0, 139.0, 137.0, 181.0, 135.0], "value": 142.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 173.0, 170.0, 168.0, 170.0, 175.0, 176.0, 168.0, 171.0, 172.0], "value": 171.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 69.0, 69.0, 70.0, 68.0, 69.0, 70.0, 75.0, 97.0], "value": 72.5, "unit": "score"}], "name": "kraken", "value": 1484.7, "alertThreshold": 2.0}]} 07:09:49 INFO - Return code: 0 07:09:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:09:49 ERROR - # TBPL SUCCESS # 07:09:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:09:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:09:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:09:49 INFO - 2016-04-23 07:09:49.219 screenresolution[1952:18000] starting screenresolution argv=screenresolution get 07:09:49 INFO - 2016-04-23 07:09:49.222 screenresolution[1952:18000] Display 0: 1600x1200x32@60 07:09:49 INFO - 2016-04-23 07:09:49.229 screenresolution[1953:18003] starting screenresolution argv=screenresolution list 07:09:49 INFO - Available Modes on Display 0 07:09:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:09:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:09:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:09:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:09:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:09:49 INFO - Intel Iris: 07:09:49 INFO - Chipset Model: Intel Iris 07:09:49 INFO - Type: GPU 07:09:49 INFO - Bus: Built-In 07:09:49 INFO - VRAM (Dynamic, Max): 1536 MB 07:09:49 INFO - Vendor: Intel (0x8086) 07:09:49 INFO - Device ID: 0x0a2e 07:09:49 INFO - Revision ID: 0x0009 07:09:49 INFO - Displays: 07:09:49 INFO - Display: 07:09:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:09:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:09:49 INFO - Display Serial Number: 1600x1200 60 07:09:49 INFO - Main Display: Yes 07:09:49 INFO - Mirror: Off 07:09:49 INFO - Online: Yes 07:09:49 INFO - Rotation: Supported 07:09:49 INFO - Return code: 0 07:09:49 INFO - Running post-action listener: _resource_record_post_action 07:09:49 INFO - Running post-run listener: _resource_record_post_run 07:09:50 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 696320; Write bytes: 693941248; Read time: 8; Write time: 2145 07:09:50 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 384254464; Write bytes: 378554368; Read time: 28556; Write time: 728 07:09:50 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 692224; Write bytes: 295595008; Read time: 8; Write time: 1376 07:09:50 INFO - Running post-run listener: _upload_blobber_files 07:09:50 INFO - Blob upload gear active. 07:09:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:09:50 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:09:50 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:09:50 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:09:50 INFO - (blobuploader) - INFO - Open directory for files ... 07:09:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:09:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:09:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:09:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:09:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:09:52 INFO - (blobuploader) - INFO - Done attempting. 07:09:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:09:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:09:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:09:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:09:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:09:53 INFO - (blobuploader) - INFO - Done attempting. 07:09:53 INFO - (blobuploader) - INFO - Iteration through files over. 07:09:53 INFO - Return code: 0 07:09:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:09:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:09:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9280b620c8f70f9729102d73cbfcc168a9f9dc059273926f8855438c344fed5bd78b00c8aa65f0424cd8eb1ed6912f4da332fafca3b553915390b34390a6f3bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8eb887e69e9344222655c48dcf346af1f3c5edc39f7f9a9f79d9d333ccfc1ee8b0e41760b39e216da84ce1774532281bb8d639f8e63c288ca577dd8c53ef0853"} 07:09:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:09:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:09:53 INFO - Contents: 07:09:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9280b620c8f70f9729102d73cbfcc168a9f9dc059273926f8855438c344fed5bd78b00c8aa65f0424cd8eb1ed6912f4da332fafca3b553915390b34390a6f3bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8eb887e69e9344222655c48dcf346af1f3c5edc39f7f9a9f79d9d333ccfc1ee8b0e41760b39e216da84ce1774532281bb8d639f8e63c288ca577dd8c53ef0853"} 07:09:53 INFO - Running post-run listener: copy_logs_to_upload_dir 07:09:53 INFO - Copying logs to upload dir... 07:09:53 INFO - mkdir: /builds/slave/test/build/upload/logs 07:09:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1333.464057 ========= master_lag: 49.36 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 2 secs) (at 2016-04-23 07:10:42.429721) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 10 secs) (at 2016-04-23 07:10:42.430777) ========= 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.kZmTHcAoWZ/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.inGUcsUWGh/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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/Firefox/sha512/9280b620c8f70f9729102d73cbfcc168a9f9dc059273926f8855438c344fed5bd78b00c8aa65f0424cd8eb1ed6912f4da332fafca3b553915390b34390a6f3bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8eb887e69e9344222655c48dcf346af1f3c5edc39f7f9a9f79d9d333ccfc1ee8b0e41760b39e216da84ce1774532281bb8d639f8e63c288ca577dd8c53ef0853"} build_url:https://queue.taskcluster.net/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011379 build_url: 'https://queue.taskcluster.net/v1/task/de7VZhOSTu-1W5w3i5HcaQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9280b620c8f70f9729102d73cbfcc168a9f9dc059273926f8855438c344fed5bd78b00c8aa65f0424cd8eb1ed6912f4da332fafca3b553915390b34390a6f3bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8eb887e69e9344222655c48dcf346af1f3c5edc39f7f9a9f79d9d333ccfc1ee8b0e41760b39e216da84ce1774532281bb8d639f8e63c288ca577dd8c53ef0853"}' ========= master_lag: 10.32 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 10 secs) (at 2016-04-23 07:10:52.760398) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 13 secs) (at 2016-04-23 07:10:52.760689) ========= 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.kZmTHcAoWZ/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.inGUcsUWGh/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.005536 ========= master_lag: 13.50 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 13 secs) (at 2016-04-23 07:11:06.268032) ========= ========= Started reboot skipped (results: 3, elapsed: 1 secs) (at 2016-04-23 07:11:06.268301) ========= ========= Finished reboot skipped (results: 3, elapsed: 1 secs) (at 2016-04-23 07:11:07.865198) ========= ========= Total master_lag: 73.25 =========