builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0118 starttime: 1465235286.35 results: success (0) buildid: 20160606053329 builduid: d7acf4330f0b47b6844d82d15649fe74 revision: 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:06.354938) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:06.355432) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:06.355732) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:07.163667) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:07.163972) ========= 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.KBMNcLRUqf/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.0hriJA4zPA/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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-06-06 10:48:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.51M=0.002s 2016-06-06 10:48:07 (7.51 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.466366 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:07.651385) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:07.651772) ========= 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.KBMNcLRUqf/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.0hriJA4zPA/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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.019996 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:07.701190) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-06 10:48:07.701571) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KBMNcLRUqf/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.0hriJA4zPA/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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-06-06 10:48:07,768 truncating revision to first 12 chars 2016-06-06 10:48:07,768 Setting DEBUG logging. 2016-06-06 10:48:07,768 attempt 1/10 2016-06-06 10:48:07,768 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5c8f37e61ac7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-06 10:48:08,581 unpacking tar archive at: mozilla-aurora-5c8f37e61ac7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.062322 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-06 10:48:08.785968) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:08.786260) ========= script_repo_revision: 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:08.786694) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:08.786965) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:08.803168) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-06-06 10:48:08.803450) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KBMNcLRUqf/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.0hriJA4zPA/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 10:48:08 INFO - MultiFileLogger online at 20160606 10:48:08 in /builds/slave/test 10:48:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 10:48:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:48:08 INFO - {'append_to_log': False, 10:48:08 INFO - 'base_work_dir': '/builds/slave/test', 10:48:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:48:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:48:08 INFO - 'branch': 'Mozilla-Aurora', 10:48:08 INFO - 'buildbot_json_path': 'buildprops.json', 10:48:08 INFO - 'config_files': ('talos/mac_config.py',), 10:48:08 INFO - 'default_actions': ('clobber', 10:48:08 INFO - 'read-buildbot-config', 10:48:08 INFO - 'download-and-extract', 10:48:08 INFO - 'populate-webroot', 10:48:08 INFO - 'create-virtualenv', 10:48:08 INFO - 'install', 10:48:08 INFO - 'run-tests'), 10:48:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:48:08 INFO - 'download_minidump_stackwalk': True, 10:48:08 INFO - 'download_symbols': 'ondemand', 10:48:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:48:08 INFO - 'tooltool.py': '/tools/tooltool.py', 10:48:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:48:08 INFO - '/tools/misc-python/virtualenv.py')}, 10:48:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:48:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:48:08 INFO - 'installer_path': 'installer.exe', 10:48:08 INFO - 'log_level': 'info', 10:48:08 INFO - 'log_name': 'talos', 10:48:08 INFO - 'log_to_console': True, 10:48:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:48:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:48:08 INFO - 'opt_config_files': (), 10:48:08 INFO - 'pip_index': False, 10:48:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:48:08 INFO - 'cmd': ('bash', 10:48:08 INFO - '-c', 10:48:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:48:08 INFO - 'enabled': True, 10:48:08 INFO - 'halt_on_failure': False, 10:48:08 INFO - 'name': 'check_screen_resolution'},), 10:48:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:48:08 INFO - 'cmd': ('bash', 10:48:08 INFO - '-c', 10:48:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:48:08 INFO - 'enabled': True, 10:48:08 INFO - 'halt_on_failure': False, 10:48:08 INFO - 'name': 'check_screen_resolution'},), 10:48:08 INFO - 'run_cmd_checks_enabled': True, 10:48:08 INFO - 'sps_profile': False, 10:48:08 INFO - 'sps_profile_interval': 0, 10:48:08 INFO - 'suite': 'dromaeojs', 10:48:08 INFO - 'system_bits': '32', 10:48:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:48:08 INFO - 'title': 't-yosemite-r7-0118', 10:48:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:48:08 INFO - 'use_talos_json': True, 10:48:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:48:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:48:08 INFO - 'work_dir': 'build'} 10:48:08 INFO - ##### 10:48:08 INFO - ##### Running clobber step. 10:48:08 INFO - ##### 10:48:08 INFO - Running pre-action listener: _resource_record_pre_action 10:48:08 INFO - Running main action method: clobber 10:48:08 INFO - rmtree: /builds/slave/test/build 10:48:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:48:11 INFO - Running post-action listener: _resource_record_post_action 10:48:11 INFO - ##### 10:48:11 INFO - ##### Running read-buildbot-config step. 10:48:11 INFO - ##### 10:48:11 INFO - Running pre-action listener: _resource_record_pre_action 10:48:11 INFO - Running main action method: read_buildbot_config 10:48:11 INFO - Using buildbot properties: 10:48:11 INFO - { 10:48:11 INFO - "project": "", 10:48:11 INFO - "product": "firefox", 10:48:11 INFO - "script_repo_revision": "production", 10:48:11 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:48:11 INFO - "repository": "", 10:48:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:48:11 INFO - "buildid": "20160606053329", 10:48:11 INFO - "pgo_build": "False", 10:48:11 INFO - "basedir": "/builds/slave/test", 10:48:11 INFO - "buildnumber": 34, 10:48:11 INFO - "slavename": "t-yosemite-r7-0118", 10:48:11 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:48:11 INFO - "platform": "yosemite_r7", 10:48:11 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:48:11 INFO - "branch": "mozilla-aurora", 10:48:11 INFO - "repo_path": "releases/mozilla-aurora", 10:48:11 INFO - "revision": "5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64", 10:48:11 INFO - "stage_platform": "macosx64", 10:48:11 INFO - "builduid": "d7acf4330f0b47b6844d82d15649fe74", 10:48:11 INFO - "slavebuilddir": "test" 10:48:11 INFO - } 10:48:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 10:48:11 INFO - Running post-action listener: _resource_record_post_action 10:48:11 INFO - ##### 10:48:11 INFO - ##### Running download-and-extract step. 10:48:11 INFO - ##### 10:48:11 INFO - Running pre-action listener: _resource_record_pre_action 10:48:11 INFO - Running main action method: download_and_extract 10:48:11 INFO - mkdir: /builds/slave/test/build/tests 10:48: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')]} 10:48:11 INFO - https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:48:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 10:48:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 10:48:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 10:48:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 10:48:13 INFO - Downloaded 1199 bytes. 10:48:13 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 10:48:13 INFO - Using the following test package requirements: 10:48:13 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 10:48:13 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:13 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 10:48:13 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:13 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 10:48:13 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:13 INFO - u'jsshell-mac.zip'], 10:48:13 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:13 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 10:48:13 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 10:48:13 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:13 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 10:48:13 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:13 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 10:48:13 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:13 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 10:48:13 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:13 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 10:48:13 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:48: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')]} 10:48:13 INFO - https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:48:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:48:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:48:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:48:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:48:15 INFO - Downloaded 18466753 bytes. 10:48:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:48:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:48:18 INFO - Return code: 0 10:48:18 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 10:48:18 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')]} 10:48:18 INFO - https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:48:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:48:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:48:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:48:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:48:24 INFO - Downloaded 18466753 bytes. 10:48:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:48:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:48:28 INFO - Return code: 0 10:48:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:28 INFO - https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:48:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:48:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:48:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:48:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:48:29 INFO - Downloaded 10840145 bytes. 10:48:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:48:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:48:29 INFO - Return code: 0 10:48:29 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')]} 10:48:29 INFO - https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 10:48:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:48:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:48:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 10:48:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 10:48:33 INFO - Downloaded 114175355 bytes. 10:48:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:48:33 INFO - mkdir: /builds/slave/test/properties 10:48:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:48:33 INFO - Writing to file /builds/slave/test/properties/build_url 10:48:33 INFO - Contents: 10:48:33 INFO - build_url:https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:48:34 INFO - Running post-action listener: _resource_record_post_action 10:48:34 INFO - Running post-action listener: set_extra_try_arguments 10:48:34 INFO - ##### 10:48:34 INFO - ##### Running populate-webroot step. 10:48:34 INFO - ##### 10:48:34 INFO - Running pre-action listener: _resource_record_pre_action 10:48:34 INFO - Running main action method: populate_webroot 10:48:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:48:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:48:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:48:34 INFO - '--tppagecycles', 10:48:34 INFO - '7'], 10:48:34 INFO - 'tests': ['tsvgm']}}, 10:48:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:48:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:48:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:48:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:48:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:48:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:48:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:34 INFO - 'talos_options': ['--disable-e10s'], 10:48:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:48:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:48:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:34 INFO - 'talos_options': ['--disable-e10s'], 10:48:34 INFO - 'tests': ['damp', 'tps']}, 10:48:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:34 INFO - 'tests': ['damp', 'tps']}, 10:48:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:48:34 INFO - 'tests': ['dromaeo_dom']}, 10:48:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:48:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:48:34 INFO - 'tests': ['a11yr', 10:48:34 INFO - 'ts_paint', 10:48:34 INFO - 'tpaint', 10:48:34 INFO - 'sessionrestore', 10:48:34 INFO - 'sessionrestore_no_auto_restore', 10:48:34 INFO - 'tabpaint']}, 10:48:34 INFO - 'other-e10s': {'tests': ['a11yr', 10:48:34 INFO - 'ts_paint', 10:48:34 INFO - 'tpaint', 10:48:34 INFO - 'sessionrestore', 10:48:34 INFO - 'sessionrestore_no_auto_restore', 10:48:34 INFO - 'tabpaint']}, 10:48:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:48:34 INFO - 'ts_paint', 10:48:34 INFO - 'tpaint', 10:48:34 INFO - 'sessionrestore', 10:48:34 INFO - 'sessionrestore_no_auto_restore', 10:48:34 INFO - 'tabpaint']}, 10:48:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:48:34 INFO - 'ts_paint', 10:48:34 INFO - 'tpaint', 10:48:34 INFO - 'sessionrestore', 10:48:34 INFO - 'sessionrestore_no_auto_restore', 10:48:34 INFO - 'tabpaint']}, 10:48:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:48:34 INFO - 'tests': ['a11yr', 10:48:34 INFO - 'ts_paint', 10:48:34 INFO - 'tpaint', 10:48:34 INFO - 'sessionrestore', 10:48:34 INFO - 'sessionrestore_no_auto_restore', 10:48:34 INFO - 'tabpaint']}, 10:48:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:48:34 INFO - 'tests': ['a11yr', 10:48:34 INFO - 'ts_paint', 10:48:34 INFO - 'tpaint', 10:48:34 INFO - 'sessionrestore', 10:48:34 INFO - 'sessionrestore_no_auto_restore', 10:48:34 INFO - 'tabpaint']}, 10:48:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:48:34 INFO - 'tests': ['tsvgx', 10:48:34 INFO - 'tsvgr_opacity', 10:48:34 INFO - 'tart', 10:48:34 INFO - 'tscrollx', 10:48:34 INFO - 'cart']}, 10:48:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:48:34 INFO - 'tsvgr_opacity', 10:48:34 INFO - 'tart', 10:48:34 INFO - 'tscrollx', 10:48:34 INFO - 'cart']}, 10:48:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:34 INFO - 'talos_options': ['--disable-e10s'], 10:48:34 INFO - 'tests': ['tp5o']}, 10:48:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:34 INFO - 'tests': ['tp5o']}, 10:48:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:34 INFO - 'talos_options': ['--disable-e10s', 10:48:34 INFO - '--xperf_path', 10:48:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:48:34 INFO - 'tests': ['tp5n']}, 10:48:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:34 INFO - 'talos_options': ['--xperf_path', 10:48:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:48:34 INFO - 'tests': ['tp5n']}}, 10:48:34 INFO - 'talos.zip': {'path': '', 10:48:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:48:34 INFO - Running post-action listener: _resource_record_post_action 10:48:34 INFO - ##### 10:48:34 INFO - ##### Running create-virtualenv step. 10:48:34 INFO - ##### 10:48:34 INFO - Running pre-action listener: _resource_record_pre_action 10:48:34 INFO - Running main action method: create_virtualenv 10:48:34 INFO - Creating virtualenv /builds/slave/test/build/venv 10:48:34 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 10:48:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:48:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:48:34 INFO - Using real prefix '/tools/python27' 10:48:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:48:35 INFO - Installing distribute.............................................................................................................................................................................................done. 10:48:37 INFO - Installing pip.................done. 10:48:37 INFO - Return code: 0 10:48:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:48:37 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')]} 10:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:37 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')]} 10:48:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:37 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 0x10d988a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d998420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe321de3620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d827e00>, '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 0x10d690db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d822b20>, '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.KBMNcLRUqf/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.0hriJA4zPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 10:48:37 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 10:48:37 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 10:48:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KBMNcLRUqf/Render', 10:48:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:37 INFO - 'HOME': '/Users/cltbld', 10:48:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:37 INFO - 'LOGNAME': 'cltbld', 10:48:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:37 INFO - 'MOZ_NO_REMOTE': '1', 10:48:37 INFO - 'NO_EM_RESTART': '1', 10:48:37 INFO - 'PAGER': '/bin/cat', 10:48:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:37 INFO - 'PWD': '/builds/slave/test', 10:48:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:37 INFO - 'SHELL': '/bin/bash', 10:48:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hriJA4zPA/Listeners', 10:48:37 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 10:48:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:37 INFO - 'USER': 'cltbld', 10:48:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:37 INFO - 'XPC_FLAGS': '0x0', 10:48:37 INFO - 'XPC_SERVICE_NAME': '0', 10:48:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:38 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 10:48:38 INFO - Cleaning up... 10:48:38 INFO - Return code: 0 10:48:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 10:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:38 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 0x10d988a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d998420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe321de3620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d827e00>, '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 0x10d690db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d822b20>, '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.KBMNcLRUqf/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.0hriJA4zPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 10:48:38 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 10:48:38 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 10:48:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KBMNcLRUqf/Render', 10:48:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:38 INFO - 'HOME': '/Users/cltbld', 10:48:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:38 INFO - 'LOGNAME': 'cltbld', 10:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:38 INFO - 'MOZ_NO_REMOTE': '1', 10:48:38 INFO - 'NO_EM_RESTART': '1', 10:48:38 INFO - 'PAGER': '/bin/cat', 10:48:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:38 INFO - 'PWD': '/builds/slave/test', 10:48:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:38 INFO - 'SHELL': '/bin/bash', 10:48:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hriJA4zPA/Listeners', 10:48:38 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 10:48:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:38 INFO - 'USER': 'cltbld', 10:48:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:38 INFO - 'XPC_FLAGS': '0x0', 10:48:38 INFO - 'XPC_SERVICE_NAME': '0', 10:48:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:38 INFO - Downloading/unpacking psutil>=0.7.1 10:48: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 10:48: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 10:48: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 10:48: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 10:48: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 10:48: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 10:48:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:48:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:48:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:48:41 INFO - Installing collected packages: psutil 10:48:41 INFO - Running setup.py install for psutil 10:48:41 INFO - building 'psutil._psutil_osx' extension 10:48:41 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 10:48:41 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 10:48:42 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 10:48:42 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 10:48:42 INFO - building 'psutil._psutil_posix' extension 10:48:42 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 10:48:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:48:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:48:42 INFO - ^ 10:48:42 INFO - 1 warning generated. 10:48:42 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 10:48:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:48:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:48:42 INFO - Successfully installed psutil 10:48:42 INFO - Cleaning up... 10:48:42 INFO - Return code: 0 10:48:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 10:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:42 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 0x10d988a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d998420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe321de3620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d827e00>, '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 0x10d690db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d822b20>, '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.KBMNcLRUqf/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.0hriJA4zPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 10:48:42 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 10:48:42 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 10:48:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KBMNcLRUqf/Render', 10:48:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:42 INFO - 'HOME': '/Users/cltbld', 10:48:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:42 INFO - 'LOGNAME': 'cltbld', 10:48:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:42 INFO - 'MOZ_NO_REMOTE': '1', 10:48:42 INFO - 'NO_EM_RESTART': '1', 10:48:42 INFO - 'PAGER': '/bin/cat', 10:48:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:42 INFO - 'PWD': '/builds/slave/test', 10:48:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:42 INFO - 'SHELL': '/bin/bash', 10:48:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hriJA4zPA/Listeners', 10:48:42 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 10:48:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:42 INFO - 'USER': 'cltbld', 10:48:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:42 INFO - 'XPC_FLAGS': '0x0', 10:48:42 INFO - 'XPC_SERVICE_NAME': '0', 10:48:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:48:42 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 10:48:42 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 10:48:42 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 10:48:42 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 10:48:42 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 10:48:42 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 10:48:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:48:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:48:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:48:45 INFO - Installing collected packages: mozsystemmonitor 10:48:45 INFO - Running setup.py install for mozsystemmonitor 10:48:45 INFO - Successfully installed mozsystemmonitor 10:48:45 INFO - Cleaning up... 10:48:45 INFO - Return code: 0 10:48:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:48:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:45 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 0x10d988a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d998420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe321de3620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d827e00>, '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 0x10d690db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d822b20>, '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.KBMNcLRUqf/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.0hriJA4zPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 10:48:45 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 10:48:45 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 10:48:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KBMNcLRUqf/Render', 10:48:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:45 INFO - 'HOME': '/Users/cltbld', 10:48:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:45 INFO - 'LOGNAME': 'cltbld', 10:48:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:45 INFO - 'MOZ_NO_REMOTE': '1', 10:48:45 INFO - 'NO_EM_RESTART': '1', 10:48:45 INFO - 'PAGER': '/bin/cat', 10:48:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:45 INFO - 'PWD': '/builds/slave/test', 10:48:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:45 INFO - 'SHELL': '/bin/bash', 10:48:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hriJA4zPA/Listeners', 10:48:45 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 10:48:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:45 INFO - 'USER': 'cltbld', 10:48:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:45 INFO - 'XPC_FLAGS': '0x0', 10:48:45 INFO - 'XPC_SERVICE_NAME': '0', 10:48:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:45 INFO - Downloading/unpacking blobuploader==1.2.4 10:48:45 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 10:48:45 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 10:48:45 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 10:48:45 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 10:48:45 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 10:48:45 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 10:48:48 INFO - Downloading blobuploader-1.2.4.tar.gz 10:48:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:48:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:48:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:49 INFO - Downloading docopt-0.6.1.tar.gz 10:48:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:48:49 INFO - Installing collected packages: blobuploader, requests, docopt 10:48:49 INFO - Running setup.py install for blobuploader 10:48:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:48:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:48:49 INFO - Running setup.py install for requests 10:48:49 INFO - Running setup.py install for docopt 10:48:50 INFO - Successfully installed blobuploader requests docopt 10:48:50 INFO - Cleaning up... 10:48:50 INFO - Return code: 0 10:48:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:50 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 0x10d988a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d998420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe321de3620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d827e00>, '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 0x10d690db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d822b20>, '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.KBMNcLRUqf/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.0hriJA4zPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 10:48:50 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 10:48:50 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 10:48:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KBMNcLRUqf/Render', 10:48:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:50 INFO - 'HOME': '/Users/cltbld', 10:48:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:50 INFO - 'LOGNAME': 'cltbld', 10:48:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:50 INFO - 'MOZ_NO_REMOTE': '1', 10:48:50 INFO - 'NO_EM_RESTART': '1', 10:48:50 INFO - 'PAGER': '/bin/cat', 10:48:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:50 INFO - 'PWD': '/builds/slave/test', 10:48:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:50 INFO - 'SHELL': '/bin/bash', 10:48:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hriJA4zPA/Listeners', 10:48:50 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 10:48:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:50 INFO - 'USER': 'cltbld', 10:48:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:50 INFO - 'XPC_FLAGS': '0x0', 10:48:50 INFO - 'XPC_SERVICE_NAME': '0', 10:48:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:48:50 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-vPOevA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:48:50 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-_wRyBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:48:50 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-zd1Xad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:48:50 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-59OemW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:48:50 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-9qoFdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:48:51 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-saXhUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:48:51 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-LqP5Wf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:48:51 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-_hlWFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:48:51 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-dPp698-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:48:51 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-pNO90w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:48:51 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-0NJjNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:48:51 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-2aqskS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:48:51 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-x_i5on-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:48:51 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-gUzxJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:51 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-EezEAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:48:52 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-V0f2YP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:48:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:48:52 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-3Q1twh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:48:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:48:52 INFO - Running setup.py install for manifestparser 10:48:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:48:52 INFO - Running setup.py install for mozcrash 10:48:52 INFO - Running setup.py install for mozdebug 10:48:52 INFO - Running setup.py install for mozdevice 10:48:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:48:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:48:52 INFO - Running setup.py install for mozfile 10:48:53 INFO - Running setup.py install for mozhttpd 10:48:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:48:53 INFO - Running setup.py install for mozinfo 10:48:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:48:53 INFO - Running setup.py install for mozInstall 10:48:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:48:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:48:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:48:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:48:53 INFO - Running setup.py install for mozleak 10:48:53 INFO - Running setup.py install for mozlog 10:48:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:48:53 INFO - Running setup.py install for moznetwork 10:48:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:48:53 INFO - Running setup.py install for mozprocess 10:48:54 INFO - Running setup.py install for mozprofile 10:48:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:48:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:48:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:48:54 INFO - Running setup.py install for mozrunner 10:48:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:48:54 INFO - Running setup.py install for mozscreenshot 10:48:54 INFO - Running setup.py install for moztest 10:48:54 INFO - Running setup.py install for mozversion 10:48:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:48:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:48:54 INFO - Cleaning up... 10:48:54 INFO - Return code: 0 10:48:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10: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')]} 10:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10: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')]} 10:48:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:54 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 0x10d988a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d998420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe321de3620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d827e00>, '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 0x10d690db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d822b20>, '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.KBMNcLRUqf/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.0hriJA4zPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 10:48:54 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 10:48:54 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 10:48:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KBMNcLRUqf/Render', 10:48:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:54 INFO - 'HOME': '/Users/cltbld', 10:48:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:54 INFO - 'LOGNAME': 'cltbld', 10:48:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:54 INFO - 'MOZ_NO_REMOTE': '1', 10:48:54 INFO - 'NO_EM_RESTART': '1', 10:48:54 INFO - 'PAGER': '/bin/cat', 10:48:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:54 INFO - 'PWD': '/builds/slave/test', 10:48:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:54 INFO - 'SHELL': '/bin/bash', 10:48:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hriJA4zPA/Listeners', 10:48:54 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 10:48:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:54 INFO - 'USER': 'cltbld', 10:48:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:54 INFO - 'XPC_FLAGS': '0x0', 10:48:54 INFO - 'XPC_SERVICE_NAME': '0', 10:48:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:48:55 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-JBxqoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:48:55 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)) 10:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:48:55 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-buv5Tt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:48:55 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)) 10:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:48:55 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-uOoVFb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:48:55 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)) 10:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:48:55 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-l2c2Sm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:48:55 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)) 10:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:48:55 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-kCcpv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:48:55 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)) 10:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:48:55 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-kB3W9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:48:55 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)) 10:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:48:55 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-qfHkQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:48:55 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)) 10:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:48:55 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-g9z4SS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:48:55 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)) 10:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:48:55 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-Fpz697-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:48:56 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)) 10:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:48:56 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-0U4BxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:48:56 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)) 10:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:48:56 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-ulnXHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:48:56 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)) 10:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:48:56 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-vtA5Qx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:48:56 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)) 10:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:48:56 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-zCRrVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:48:56 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)) 10:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:48:56 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-Eje8MK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:48:56 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)) 10:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:56 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-fW6FzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:56 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)) 10:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:48:56 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-cKtHpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:48:56 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)) 10:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:48:56 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-GXvdG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:48:56 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)) 10:48:56 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)) 10:48:56 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)) 10:48:56 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)) 10:48:56 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)) 10:48:56 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)) 10:48:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:48:56 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 10:48:56 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 10:48:56 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 10:48:56 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 10:48:56 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 10:48:56 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 10:48:59 INFO - Downloading blessings-1.6.tar.gz 10:48:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:48:59 INFO - Installing collected packages: blessings 10:48:59 INFO - Running setup.py install for blessings 10:48:59 INFO - Successfully installed blessings 10:48:59 INFO - Cleaning up... 10:48:59 INFO - Return code: 0 10:48:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:48:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:48:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:49:00 INFO - Reading from file tmpfile_stdout 10:49:00 INFO - Current package versions: 10:49:00 INFO - blessings == 1.6 10:49:00 INFO - blobuploader == 1.2.4 10:49:00 INFO - docopt == 0.6.1 10:49:00 INFO - manifestparser == 1.1 10:49:00 INFO - mozInstall == 1.12 10:49:00 INFO - mozcrash == 0.17 10:49:00 INFO - mozdebug == 0.1 10:49:00 INFO - mozdevice == 0.48 10:49:00 INFO - mozfile == 1.2 10:49:00 INFO - mozhttpd == 0.7 10:49:00 INFO - mozinfo == 0.9 10:49:00 INFO - mozleak == 0.1 10:49:00 INFO - mozlog == 3.1 10:49:00 INFO - moznetwork == 0.27 10:49:00 INFO - mozprocess == 0.22 10:49:00 INFO - mozprofile == 0.28 10:49:00 INFO - mozrunner == 6.11 10:49:00 INFO - mozscreenshot == 0.1 10:49:00 INFO - mozsystemmonitor == 0.0 10:49:00 INFO - moztest == 0.7 10:49:00 INFO - mozversion == 1.4 10:49:00 INFO - psutil == 3.1.1 10:49:00 INFO - requests == 1.2.3 10:49:00 INFO - wsgiref == 0.1.2 10:49:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10: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')]} 10:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10: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')]} 10:49:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:00 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 0x10d988a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d998420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe321de3620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d827e00>, '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 0x10d690db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d822b20>, '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.KBMNcLRUqf/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.0hriJA4zPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 10:49:00 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 10:49:00 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 10:49:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KBMNcLRUqf/Render', 10:49:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:00 INFO - 'HOME': '/Users/cltbld', 10:49:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:00 INFO - 'LOGNAME': 'cltbld', 10:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:00 INFO - 'MOZ_NO_REMOTE': '1', 10:49:00 INFO - 'NO_EM_RESTART': '1', 10:49:00 INFO - 'PAGER': '/bin/cat', 10:49:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:00 INFO - 'PWD': '/builds/slave/test', 10:49:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:00 INFO - 'SHELL': '/bin/bash', 10:49:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hriJA4zPA/Listeners', 10:49:00 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 10:49:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:00 INFO - 'USER': 'cltbld', 10:49:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:00 INFO - 'XPC_FLAGS': '0x0', 10:49:00 INFO - 'XPC_SERVICE_NAME': '0', 10:49:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:00 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)) 10:49:00 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)) 10:49:00 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)) 10:49:00 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)) 10:49:00 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)) 10:49:00 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)) 10:49:00 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)) 10:49:00 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)) 10:49:00 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)) 10:49:00 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)) 10:49:00 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)) 10:49:00 INFO - Cleaning up... 10:49:00 INFO - Return code: 0 10:49:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10: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')]} 10:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10: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')]} 10:49:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:00 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 0x10d988a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d998420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe321de3620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d827e00>, '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 0x10d690db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d822b20>, '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.KBMNcLRUqf/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.0hriJA4zPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 10:49:00 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 10:49:00 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 10:49:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KBMNcLRUqf/Render', 10:49:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:00 INFO - 'HOME': '/Users/cltbld', 10:49:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:00 INFO - 'LOGNAME': 'cltbld', 10:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:00 INFO - 'MOZ_NO_REMOTE': '1', 10:49:00 INFO - 'NO_EM_RESTART': '1', 10:49:00 INFO - 'PAGER': '/bin/cat', 10:49:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:00 INFO - 'PWD': '/builds/slave/test', 10:49:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:00 INFO - 'SHELL': '/bin/bash', 10:49:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hriJA4zPA/Listeners', 10:49:00 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 10:49:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:00 INFO - 'USER': 'cltbld', 10:49:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:00 INFO - 'XPC_FLAGS': '0x0', 10:49:00 INFO - 'XPC_SERVICE_NAME': '0', 10:49:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:00 INFO - Downloading/unpacking jsonschema 10:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:49:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:49:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:49:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:49:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:49:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:49:02 INFO - Downloading/unpacking functools32 (from jsonschema) 10:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:49:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:49:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:49:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:49:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:49:03 INFO - Downloading functools32-3.2.3-2.tar.gz 10:49:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:49:03 INFO - warning: no files found matching '*.txt' 10:49:03 INFO - no previously-included directories found matching 'build' 10:49:03 INFO - no previously-included directories found matching 'dist' 10:49:03 INFO - no previously-included directories found matching '.git*' 10:49:03 INFO - Installing collected packages: jsonschema, functools32 10:49:03 INFO - Running setup.py install for functools32 10:49:03 INFO - warning: no files found matching '*.txt' 10:49:03 INFO - no previously-included directories found matching 'build' 10:49:03 INFO - no previously-included directories found matching 'dist' 10:49:03 INFO - no previously-included directories found matching '.git*' 10:49:03 INFO - Successfully installed jsonschema functools32 10:49:03 INFO - Cleaning up... 10:49:03 INFO - Return code: 0 10:49:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:49:03 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')]} 10:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:03 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')]} 10:49:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:03 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 0x10d988a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d998420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe321de3620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d827e00>, '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 0x10d690db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d822b20>, '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.KBMNcLRUqf/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.0hriJA4zPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 10:49:03 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 10:49:03 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 10:49:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KBMNcLRUqf/Render', 10:49:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:03 INFO - 'HOME': '/Users/cltbld', 10:49:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:03 INFO - 'LOGNAME': 'cltbld', 10:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:03 INFO - 'MOZ_NO_REMOTE': '1', 10:49:03 INFO - 'NO_EM_RESTART': '1', 10:49:03 INFO - 'PAGER': '/bin/cat', 10:49:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:03 INFO - 'PWD': '/builds/slave/test', 10:49:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:03 INFO - 'SHELL': '/bin/bash', 10:49:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hriJA4zPA/Listeners', 10:49:03 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 10:49:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:03 INFO - 'USER': 'cltbld', 10:49:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:03 INFO - 'XPC_FLAGS': '0x0', 10:49:03 INFO - 'XPC_SERVICE_NAME': '0', 10:49:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:03 INFO - Downloading/unpacking flake8 10:49:03 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 10:49:03 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 10:49:03 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 10:49:03 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 10:49:03 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 10:49:03 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 10:49:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:49:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:49:06 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 10:49:06 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 10:49:06 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 10:49:06 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 10:49:06 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 10:49:06 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 10:49:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:49:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:49:06 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 10:49:06 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 10:49:06 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 10:49:06 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 10:49:06 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 10:49:06 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 10:49:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:49:07 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 10:49:07 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 10:49:07 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 10:49:07 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 10:49:07 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 10:49:07 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 10:49:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:49:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:49:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:49:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:49:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:49:07 INFO - no previously-included directories found matching 'docs/_build' 10:49:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:49:07 INFO - Running setup.py install for pep8 10:49:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:49:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:49:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:49:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:49:07 INFO - no previously-included directories found matching 'docs/_build' 10:49:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:49:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:49:07 INFO - Cleaning up... 10:49:08 INFO - Return code: 0 10:49:08 INFO - Running post-action listener: _resource_record_post_action 10:49:08 INFO - Running post-action listener: _start_resource_monitoring 10:49:08 INFO - Starting resource monitoring. 10:49:08 INFO - ##### 10:49:08 INFO - ##### Running install step. 10:49:08 INFO - ##### 10:49:08 INFO - Running pre-action listener: _resource_record_pre_action 10:49:08 INFO - Running main action method: install 10:49:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:49:08 INFO - Reading from file tmpfile_stdout 10:49:08 INFO - Detecting whether we're running mozinstall >=1.0... 10:49:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:49:08 INFO - Reading from file tmpfile_stdout 10:49:08 INFO - Output received: 10:49:08 INFO - Usage: mozinstall [options] installer 10:49:08 INFO - Options: 10:49:08 INFO - -h, --help show this help message and exit 10:49:08 INFO - -d DEST, --destination=DEST 10:49:08 INFO - Directory to install application into. [default: 10:49:08 INFO - "/builds/slave/test"] 10:49:08 INFO - --app=APP Application being installed. [default: firefox] 10:49:08 INFO - mkdir: /builds/slave/test/build/application 10:49:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 10:49:39 INFO - Reading from file tmpfile_stdout 10:49:39 INFO - Output received: 10:49:39 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:49:39 INFO - Running post-action listener: _resource_record_post_action 10:49:39 INFO - ##### 10:49:39 INFO - ##### Running run-tests step. 10:49:39 INFO - ##### 10:49:39 INFO - Running pre-action listener: _resource_record_pre_action 10:49:39 INFO - Running main action method: run_tests 10:49:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:49:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:49:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:49:39 INFO - 2016-06-06 10:49:39.978 screenresolution[1934:19322] starting screenresolution argv=screenresolution get 10:49:39 INFO - 2016-06-06 10:49:39.981 screenresolution[1934:19322] Display 0: 1600x1200x32@60 10:49:39 INFO - 2016-06-06 10:49:39.987 screenresolution[1935:19324] starting screenresolution argv=screenresolution list 10:49:39 INFO - Available Modes on Display 0 10:49:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:49:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:49:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:49:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:49:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:49:40 INFO - Intel Iris: 10:49:40 INFO - Chipset Model: Intel Iris 10:49:40 INFO - Type: GPU 10:49:40 INFO - Bus: Built-In 10:49:40 INFO - VRAM (Dynamic, Max): 1536 MB 10:49:40 INFO - Vendor: Intel (0x8086) 10:49:40 INFO - Device ID: 0x0a2e 10:49:40 INFO - Revision ID: 0x0009 10:49:40 INFO - Displays: 10:49:40 INFO - Display: 10:49:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:49:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:49:40 INFO - Display Serial Number: 1600x1200 60 10:49:40 INFO - Main Display: Yes 10:49:40 INFO - Mirror: Off 10:49:40 INFO - Online: Yes 10:49:40 INFO - Rotation: Supported 10:49:40 INFO - Return code: 0 10:49:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:49:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:49:40 INFO - Python 2.7.3 10:49:40 INFO - Return code: 0 10:49:40 INFO - grabbing minidump binary from tooltool 10:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:49:40 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 0x10d827e00>, '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 0x10d690db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d822b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:49:40 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 10:49:40 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 10:49:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:49:40 INFO - Return code: 0 10:49:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:49:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:49:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:49:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:49:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:49:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:49:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:49:41 INFO - Return code: 0 10:49:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0118', '--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 10:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0118 --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 10:49:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KBMNcLRUqf/Render', 10:49:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:41 INFO - 'HOME': '/Users/cltbld', 10:49:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:41 INFO - 'LOGNAME': 'cltbld', 10:49:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:49:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:41 INFO - 'MOZ_NO_REMOTE': '1', 10:49:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:49:41 INFO - 'NO_EM_RESTART': '1', 10:49:41 INFO - 'PAGER': '/bin/cat', 10:49:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:41 INFO - 'PWD': '/builds/slave/test', 10:49:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:49:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:41 INFO - 'SHELL': '/bin/bash', 10:49:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hriJA4zPA/Listeners', 10:49:41 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 10:49:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:41 INFO - 'USER': 'cltbld', 10:49:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:41 INFO - 'XPC_FLAGS': '0x0', 10:49:41 INFO - 'XPC_SERVICE_NAME': '0', 10:49:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0118', '--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 10:49:41 INFO - mozversion application_buildid: 20160606053329 10:49:41 INFO - mozversion application_changeset: 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 10:49:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:49:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:49:41 INFO - mozversion application_name: Firefox 10:49:41 INFO - mozversion application_remotingname: firefox-dev 10:49:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:49:41 INFO - mozversion application_vendor: Mozilla 10:49:41 INFO - mozversion application_version: 48.0a2 10:49:41 INFO - mozversion platform_buildid: 20160606053329 10:49:41 INFO - mozversion platform_changeset: 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 10:49:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:49:41 INFO - mozversion platform_version: 48.0a2 10:49:41 INFO - using testdate: 1465235381 10:49:41 INFO - actual date: 1465235381 10:49:41 INFO - starting webserver on 'localhost:57384' 10:49:41 INFO - SUITE-START | Running 2 tests 10:49:41 INFO - TEST-START | dromaeo_css 10:49:41 INFO - operating with platform_type : mac_ 10:49:41 INFO - Initialising browser for dromaeo_css test... 10:49:41 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmpuA6ggY/profile http://localhost:57384/getInfo.html) 10:49:44 INFO - PROCESS | 1953 | __metrics Screen width/height:1600/1200 10:49:44 INFO - PROCESS | 1953 | colorDepth:24 10:49:44 INFO - PROCESS | 1953 | Browser inner width/height: 1024/689 10:49:44 INFO - PROCESS | 1953 | __metrics 10:49:44 INFO - PROCESS | 1953 | 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" 10:49:44 ERROR - PROCESS | 1953 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:49:44 INFO - TEST-INFO | 1953: exit 0 10:49:44 INFO - Browser initialized. 10:49:44 INFO - Running cycle 1/1 for dromaeo_css test... 10:49:44 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmpuA6ggY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:49:56 INFO - PROCESS | 1955 | RSS: Main: 211673088 10:49:56 INFO - PROCESS | 1955 | 10:50:47 INFO - PROCESS | 1955 | 1465235447927 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] 10:52:58 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:57384/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57384/tests/dromaeo/cssquery-ext.html) 10:52:59 INFO - PROCESS | 1955 | RSS: Main: 374386688 10:52:59 INFO - PROCESS | 1955 | 10:56:03 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:57384/tests/dromaeo/cssquery-ext.html (next: http://localhost:57384/tests/dromaeo/cssquery-jquery.html) 10:56:03 INFO - PROCESS | 1955 | RSS: Main: 351772672 10:56:03 INFO - PROCESS | 1955 | 10:59:05 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:57384/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57384/tests/dromaeo/cssquery-mootools.html) 10:59:06 INFO - PROCESS | 1955 | RSS: Main: 357097472 10:59:06 INFO - PROCESS | 1955 | 11:02:08 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:57384/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57384/tests/dromaeo/cssquery-prototype.html) 11:02:09 INFO - PROCESS | 1955 | RSS: Main: 358526976 11:02:09 INFO - PROCESS | 1955 | 11:05:12 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:57384/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57384/tests/dromaeo/cssquery-yui.html) 11:05:12 INFO - PROCESS | 1955 | RSS: Main: 325165056 11:05:12 INFO - PROCESS | 1955 | 11:08:15 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:57384/tests/dromaeo/cssquery-yui.html (next: http://localhost:57384/tests/dromaeo/cssquery-dojo.html) 11:08:16 INFO - PROCESS | 1955 | RSS: Main: 362287104 11:08:16 INFO - PROCESS | 1955 | 11:08:16 INFO - PROCESS | 1955 | __start_tp_report 11:08:16 INFO - PROCESS | 1955 | _x_x_mozilla_page_load 11:08:16 INFO - PROCESS | 1955 | _x_x_mozilla_page_load_details 11:08:16 INFO - PROCESS | 1955 | |i|pagename|runs| 11:08:16 INFO - PROCESS | 1955 | |0;dojo.html;3298.290867463495;3307.338532293541;3309.3877632637973;3310.652381499941;3383.7123844473217;5585.994520986221;5600.467955544227;5608.80369187078;5615.017371959906;5620.494155526002;5602.915956260659;5655.462731040555;5659.462351076651;5662.631928924617;5667.603267771247;5052.671576347544;5058.367704036996;5136.255242989773;5176.327077479922;5217.008768334023;5155.613329000321;5182.274481572583;5184.455632158626;5192.117340052181;5192.610554208431;5090.236464530331;5161.845144645647;5177.456367081459;5204;5219;4407.581279778416;5128.769205385748;5230.660007099565;5251.55361794248;5253.185756207756;5021.59827213822;5052.9242061369105;5066;5072;5166.586673066184;5423.945760542383;5489.368722596872;5527.53015993641;5541.224228607998;5565.693886836222;6144.723487443076;6178.38216178383;6190.41191086846;6199.287081985584;6201.255849298113;5780.855478613068;6071.122280061436;6104.78905871602;6219.222597175353;6222.3466536013975;5717.628354156925;5729.54163666914;5742.310922689304;5753.971230143906;5773.855653608652;6228.564000520011;6234.9688251558455;6240.188775459162;6243.562950593415;6254.34329395416;5937.732802023919;6032.065029920369;6085.604435711664;6097.725602347905;6103.992841181244;6181;6190.442860142609;6191.876162476815;6203.131561581382;6209.0996805462955;6157.630542167484;6167.938320616737;6175.62946223228;6180.6291622502795;6184.041473571604;6024.518038556905;6055.636661800218;6093.238345206849;6138.388398386059;6182.1345011698395;4831.9275210871865;4840.419149702058;4851.684640498356;4871.853844384674;4874.707517548887;4365.672574556921;4379.277419225856;4383.7588932609015;4385.649148068147;4413.602775750198;5604.075327570905;5612.961602103543;5613.578981576398;5615.522680572116;5618.887622247614;6085.939140608626;6163.660998645118;6184.752609895554;6197.907031394533;6221.253449586078;5602.467765562265;5619.157126430986;5623.128415095667;5624.156376543469;5626.97186514073;4452.510223875371;5302.734863256822;5355.598330125178;5358.571314294847;5414.160805075219;5294.841154765363;6248.343923888017;6263.812085637438;6454.225492940877;6459.192600924885;6186.597371445597;6211.751529938751;6238.532110091671;6247.0629405589525;6255.593386429868;6205.317415084427;6212.4408803207925;6315.431611155018;6331.176946996861;6501.122348482894;6049.637021778707;6091.451769340603;6134.478569321657;6134.478569321657;6134.509239260938;6171.949211970015;6175.2589689237575;6195;6205.503559715302;6206.131141640264;5866.2960444746905;5899.292084949833;6037.879242415215;6074.57477976555;6121.081837724377;6119.051547010221;6126.172966649446;6138.324784273816;6143.6928153593035;6162.630242185483;5970.462658360769;5973.790917318047;5976.641401515923;5977.820665380045;5995.340512543704;5966.612170208922;6030.7889223876955;6042.305134909541;6081.452669259788;6114.296855861633;5816.854578635568;5849.1226316052935;5856;5862.798126383996;5875.059990401515 11:08:16 INFO - PROCESS | 1955 | |1;ext.html;508.15138718340376;510.40027967138604;537.2612657595806;588.0943347245243;605.5337390209537;28222.576661350096;31410.21474463137;38750.41874371883;39311.41032884502;39562.8021859891;35322.940311790626;42223.57776422235;42566.787166064205;42886.57113428872;42917.35623965643;9480.146786789188;9796.971318011612;9800.117989380955;9811;9820.558074886612;8589.312854971618;8758.386912916098;11554.017908477748;11766.647000589954;11780;11420.371879546621;11497.080233581293;11498.827517587242;11499.712507187303;11516.596919107833;8579.871301930476;11563.710907227303;11566.942165289203;11571.768564628745;11587.884121158764;11582.6525204244;11789.469473873696;11793.938545530944;11805.291682498992;11810.586629468013;31789.36421271585;38339.616603833885;38577.421338679946;38716;38720;3244.1727359523406;3345.6654334566592;3545.5213546171194;3579.427291633326;3818.064574179316;27555.89776408941;27775.305617359525;28275.71724282772;28627.856860715565;28634.713652863415;2709.4174752428125;2731.3035176029985;2780.3883145707914;2796.9300767480977;2843.1896909380935;482515;483086;483196;483845.9999999965;484426;61109.38890611037;62396.68801656053;62401.751964960444;62479;62608.37391626026;69147;69791.3020869795;70001;70021.64989175022;70053.64973175102;17424.564385890455;17606.91196544009;17651;17669.823301767075;17674.5581360467;12623;12739.808902866464;13351.933240333736;13420.597382078358;13434.93282533581;17072.207418220907;19984.600307993758;20091.799082009285;20103.39689809308;20134.791912485296;8083.838323233503;8234.876476852853;8238.199012335834;8280.751577452686;8297.95851020753;935.1256575102301;1001.3641337750581;1037.180627304421;1041.0526420956892;1093.7101668057971;29658.851705741763;29719;30198.54702179469;30307.545386819216;30319.242018949262;1070.6038765656756;1119.1774046076127;1210.0501106631252;1236.3632729144497;1262.211118051218;15230.010049346756;15280.465183718516;15512.188790206978;15630.296636651377;15647.13940733248;597681;612523.9373803191;612764;612989;613614;56258.43741562532;56300.71849640726;61961;62031;62116.75766484645;1313.4155300891202;1416.5254639695818;1495.53638372105;1553.7591673290658;1561.4222737586938;26716.8664156678;29016.854915725286;29724.851375742983;30195.396092078474;30323.848380757954;48961.51038489712;49255.753721231165;49574.75212623914;49599.752001239765;49645.503544964085;443219;446995.7650211858;447200;447240;447438;23763.762362376154;23805.880970595037;44243;44959.55040449685;45750.54249457463;45993.54006459893;46185.538144619466;46424;46615.83460413517;47525;37422.43866342062;37611.68359107418;37622.43566346452;37658.24683506369;38371.84884453471;34056.48915266224;36912.81543592265;37585.81207093947;39245.803770980965;39382.803085984386;37935.43096853494;38357.6164238354;38774.4183837237;39096.609033910434;39635;34163.48754768631;34365.65634343625;34389.099754753755;34931.82534087313;35076;23464.6480302799;23728.339016268696;25430.618540721534;25557.872210639573;25921.351966200997 11:08:16 INFO - PROCESS | 1955 | |2;jquery.html;406.3194149799087;408.79151632667333;410.19601580901434;410.73918062030623;421.54683715006377;13858.792118118212;20218.292359767413;20674.379768606927;20835.895820520895;20871.895640521794;15564.766528502054;16138.273777680004;20327.49181270469;20640.07119679618;20664.793352066474;5575.8884822303635;5681.119426488901;5855.619384739978;5856.150858125569;5868.325142608591;4107.7535347879075;5744.16709577111;5833.620814647055;5839.678817665027;5841.386654401291;6110.1140334651445;6385.744570217185;6404.519661025419;6405.167328247321;6422.454091402236;6260.874782504325;6364.872702545947;6371.904421433682;6377.617342959414;6378.011408231709;5491.478309560625;5602.215689803431;5635.126555383922;5636.605437619368;5643.351014633328;23493.41266468335;24857.378565535826;25279.248088042463;25587;25641.871790641115;2023.9494012649654;2173.2936795541355;2261.7851304126084;2303.1593468384085;2722.2922040269473;9517.619295228182;9730.464824434654;9824.361416507903;10010.699679009642;13700.588982330546;1533.7469317562588;1593.7848390467254;1805.7652505174374;1843.364039406416;1873.0821897270375;107666;113077.43461282723;117144;118046;122633;9710.563024663907;11499.310041397375;11715.1799374043;11719.355435450962;11740.354280514483;14757.557273281818;14819.629509262355;14820.3330850112;14840.62898427548;14850.702985940437;7561.243875612395;7826.686932522635;8749.72507148146;8777.473351598925;8812.911870881337;8861.424007439497;8883.73348799537;8925.973513046083;9008.41445306053;9022.368434209542;4545.954540454619;4607.815687372467;4620.53794620542;4670.229412147026;4678.461976872703;1968.3209292793963;1979.166149707948;1979.7822239553639;1992.3026940570685;2017.404865564662;894.2801045158579;914.6067191107887;986.068165583531;999.4652860719481;1023.8157131716214;19770.60458790816;20196.29312974039;20385.388438346872;20502.897485512476;20521.076551555216;958.2382006305004;958.6859133897461;982.7297493189287;990.0792263195298;1033.3489901362093;9009.909900901039;10843.349399036084;10883.23817332779;10892.94553527243;10937.83593246102;621972;627534;635257;635315;635483;119508.8049119515;120509.39745301218;120645.39677301557;120650;122543;1004.9045340692622;1012.2205901455837;1023.4933707814679;1026.1021606094666;1028.2082796246698;17847.750657453853;17982.460526184237;18145.637087258445;18157.54606134857;18466.35367762122;51020.74489627528;51563.74218128886;53256.20115698345;53551.73224133854;54248.45751542434;69248.6537567309;72406.55186896338;72410.55178896498;74009;75045.6247718758;8005.679772809255;8035.397345199251;8120.75637730869;8263.297619702393;8413.326933845156;10364.378137311785;11389.829152562552;11892.524299027942;12104.878951210376;12383.814242786546;5528.143137813645;7268.96365518169;10060.0945914868;10598.20513461509;10652.680419587425;10137.23970702215;10255.435951022766;10386.532606032595;10643.935606439603;10670.679879603624;8279.254867061993;10346.275760696679;10662.89337106619;10997;11017.83473247886;7543.698252069856;7821.335186509211;7911.090224624242;7911.881321780293;8322.126176751595;7958.920410795818;9998.500074996367;10318.122959548522;10656.147508199481;10695.144388449062 11:08:16 INFO - PROCESS | 1955 | |3;mootools.html;1417.82986041675;1526.3513006972037;1546.1418912503564;1773.3793172389665;1931.3336898769928;7616.581088040156;8200.671973121076;8356.373272004594;8357.038940521841;8370.748877533668;8138.959305203466;8402.327813774898;8410.327173826094;8419.83160336793;8421.536815475147;2446.5718499262603;2475.1337032038823;2478.2565230430887;2479.6404521344484;2480.094765410621;2400.147947478646;2482.5407299649532;2486.154707399484;2487.3035550046015;2489.7385774493687;2083;2441.292025312657;2464.494778570389;2482.491089326693;2488.3032750829707;2444.716409923543;2465.543874383236;2466.5930121530105;2481.4292712676006;2486.154707399484;2418.201993342193;2455.742147074567;2465.938351541208;2472.11004038546;2480.2559232230437;8180.059293181301;8399.790005249857;8452.704155354533;8455.069942306342;8464.19590138942;5160.612954028463;5311.654742441729;5341.985022845685;5345.4654534546235;5355.116405793039;3506.68439840413;3566.6076731559506;3626.746127771057;3632.673059424638;3665.9083522911874;1043.6608102366686;1064.4624464645383;1066.951987160572;1067.0716476665273;1067.8024565455416;11068.39123848188;11175.664730058112;11309.830352544719;11321.603743868925;11332.036776873976;4484.1255955088445;4506.977465112654;4598.88502787433;4619;4624.907501849944;7931.444798864024;8182.590870456573;9016.692579575925;9035.909640903637;9092.8636070459;5221.477852214748;5228.372595288513;5228.477152284741;5256.894862102737;5271.789128434896;4373.650107991353;4477.977610111994;4479.7984090716;4480.036792089716;4913.901721965541;3655.8903232903053;3830.9616903830606;3907.081835768592;3907.902302442405;3909.2603791312595;1119.7738476368363;1151.9424028798694;1208.7703336366062;1229.2317301686446;1237.5854088880146;911.6080085563271;924.177482040984;951.5004622573205;955.7515046088067;967.0764419978904;5164.974175129176;5736.885262294731;5890.587658863924;5960.552958528127;6019.826133903846;903.8418276801665;929.6606738540519;964.0552258786429;967.4823969176598;988.9208863290919;3201.7598680099086;3902.902427439337;3940.014996250937;4016.397540368968;4020.0150963013666;9981.451020193814;10072.647457338959;10188.694339169688;10266.973302669823;10278.33190842593;10035.949820250851;10070.899291007143;10152.593896244067;10175;10184.287099903084;6744.325567443315;7539.509931854412;7801.102873169658;7851.685932562747;7931.920680793233;5661.547076233809;5696.609750061866;5738.024535828836;5807.041838096667;5854.531637469077;8866.246369058703;9099.226565741987;9178.128077832594;9247.907520924704;9362.485063321583;9314.72055838326;9705.5147242639;9719.65981190655;9724.416535007922;9733.977932317288;6641.402273795382;7202.171750248788;7396.482246242711;7472.813179670552;7472.850542989218;7469.439792015729;7508.136564295176;7518.28576285252;7525.247475252431;7809.062912450542;6811.523193376416;6959.965200173967;7154.964225179049;7245.710171593288;7280.708771649075;6827.419669328163;6962.443004559522;6998.580085194701;7120.287971202838;7138.107736532934;5543.805966791143;6446.033095035782;6525.9021114684265;6565.376289252514;6577.177852768404;4711.882202944954;4728.645351598712;4839.177339852163;4852;4909.790594232538;4769.403824521934;4840.515948405272;4847.563719265283;4875.366202393808;4889.266610008527 11:08:16 INFO - PROCESS | 1955 | |4;prototype.html;316.92552250221195;321.8680341060164;323.6407587577788;327.970482656561;328.4662423561712;6608.801735947917;6613.735450581977;6631.071649969008;6644.966775166124;6645.900311495331;5809.419058094199;6138.017917133259;6544.869102617945;6569.737210511574;6589.538732288742;4760.85717428476;4847.224444088946;4852.199387101124;4943.555080042795;4954.207326827708;4870.9269360959615;4935.161022626145;4941.827036053739;4960;4963.975180124095;4884.852059765952;4894.265860120975;4907.4356449008465;4914.213725803872;4914.361133052698;3906.2968665640033;4716.339712440261;4805.20714082176;4808.903821923542;4839.27410888366;4109.465769449982;4182.498100227992;4182.9790851045855;4198.971252043239;4208.347706105558;6301;6365.745370185234;6399.168108145957;6402.48780097591;6422.325655806119;5823.941760582382;5823.990723200489;5828;5833;5840.591158618899;6831.69257383414;7056.15326160864;7076;7082.681279342442;7086.503944723871;4730.432348118214;4743.525647435229;4753.619710423158;4757.762111894392;4764.809407623691;6691.464682825363;6989.301069893021;7420.146683131401;7505.624718764095;7513.699452021912;4803.687760295569;4813.181759100891;4817.975910120427;4822.758862056883;4825.059113472909;4763.261694437368;4875.853724388274;4890.095332363524;4897.828575999894;4901.09329773993;4723.385959825201;4936.308916751657;5799.5650326225705;5813.680247586339;5842.182094506772;5763.9135412968835;6455.289918109004;6532.138340573009;7131.643417829087;7136.678849451787;1670.4571014420362;1711.1700825099817;1712.5204942616087;1713.3574909408972;1717.7595136680875;1078.563181911327;1088.5210507376728;1107.6400169944638;1107.9723006924892;1111.4887151910211;6429.067785171124;6623.933760662332;6627.734890604322;6629.668516574248;6651.86696266072;7104.893426598605;7247.347738703542;7271.67277472515;7279.963600182071;7293.16128645202;5520.696361700145;6587.473002159816;6612.603243805387;6639.302873198245;6695.451410752309;3893.8831835044994;3940.665043471337;3942.5268967724055;3943.585923478051;3959.089409435846;6271.310155882849;7450.590217537979;7810.57041862703;7829.491083079582;7835.960820195863;7595.886061709079;7634.580098094547;7650.73222437212;7655.0813902332075;7656;6464.450521705708;7639.809004774814;7763.301302882767;7804.336631386395;7810.375169986388;7418.517796343221;7427.925720742939;7431.554106753612;7439.479236453396;7443.404527637667;5711.685857277889;5986.580939334205;7275.308845659654;7282.817929551592;7317.195108538163;7695.384369250334;7725.806854828675;7751.612419379121;7761.9611901940125;7773.533587984739;4846.36997190363;4850.902981940412;4851.223804191453;4854;4859.781309840995;6009.399060094131;7350.228226036189;7372.889406659012;7382.704691812267;7393.9260607393235;6948.426215425211;7079.584083183281;7152.821179470555;7154.606496642735;7167.602317548028;7065.1521817382245;7075.150981882207;7101.076860008166;7124.536905101152;7176.425885929218;6982.416505892852;6987.371136597731;6989.33601307875;6991.335823096798;7005.894911576433;7133.179684336368;7149.571025738473;7156.212816590275;7168.964155179191;7169.4622903281415;6663.800085997428;6765.2896445872475;6998;7021.859562808818;7022.578645281299 11:08:16 INFO - PROCESS | 1955 | |5;yui.html;604.314103492536;703.3810246982655;734.2547314475808;735.6064505489564;763.1376544504709;15006.324715387806;15195.84804151958;15198.54404367868;16999.57501062474;17374.305027798873;15749.448769293078;15846.762298565502;15967.920160399182;15974;15986.440474583393;746.6080307838401;759.7948553890446;925.5141050948239;929.934904556683;942.194423767679;932.9020452852454;937.8218138553688;940.7695114696909;940.952952352383;942.4769253064546;989.1196834817018;990.9603615855357;992.4541502173812;993.1458945307066;994.0010289658883;781.8319061401797;966.6520052780993;986.4919566423262;991.3902949685963;992.3698451483287;742.2095468326215;743.4647054121024;743.6356185469135;744.2520267131553;745.2994185465645;15066.945313828037;15119.773203401957;15124.319405626773;15139.394424223017;15139.924300378538;12197.329146896916;12198.57304994321;12304.569340073143;12395.380230988414;12422.75154496905;10130.594776208944;10143.644972426004;10215.89784102157;10287.537060832206;10311.535980880879;3443.3974054540354;3479.6694314040124;3588.2644057968055;3650.6349365063547;3734.719896007783;64057;69670.65164674194;75786;76629.61685191594;76675.61662191708;9187.586558604879;9213.355065145375;9243.029481904303;9472.952635236918;9499.715008549754;20278.493037674176;20397.490062748253;20399.08204130818;20440.28459003928;20448.488787780127;7172.426205903619;7177.928220717726;7224.13310402755;7341.853162936712;7590.316871481593;3967;4033.616806403387;4113.629773320416;4137.8758637240935;4305.784710764512;3524.84138213781;3571.910702232465;3573.2317551726505;3579.6957258633306;3579.83890724918;592.1737836631488;619.0992106485054;636.8567072408764;641.2209165863405;642.8714257148589;527.2328761651787;588.976440942366;608.9269287685418;614.775606903477;629.7071861584385;7856.803579910434;8192.221738934792;8219.260266576037;8233.8353232935;8329.833403331899;508.7837668990664;584.5937073733714;585.8066837943469;603.7494439807518;617.6633734614646;3741.214344987529;3836.7889766062576;3846.807659617008;3883.37865941448;3897.2205558887767;88850.11149888548;90237;90457;90588.09411905929;92802.53598732098;79161.60419197867;79842.40315193664;80166;80393;82295.17704822995;70154;71214.2878571218;72470;72529;73909.63045184739;6409.4231519163495;7710.460267781202;7751.8837217442915;7757.1079325878245;8067.435279530377;48282.31011914566;48573.27140092831;48829.5117048825;49020.26469602888;49075.509244907094;53221;64502;65876;67986.66006669935;71204.28795712184;10888;11000.724981875517;11064.778704426028;11228.719282017688;11405;11849.111316651113;11870.287782733063;11937.880621194025;12439.875601243872;12683.175593586388;8491.702790402307;10310.845337320095;10314.742131446774;10798.67603971882;11991.040716742815;11457.541698331974;11547.249428787101;11729.178957473236;11783.293002419883;11893.405329733305;9117.498537580497;11095.334279943228;11414.714632134263;11593.304401735923;11817.409129543317;9387.812243755223;9441.15029647335;9762.219022478326;9873.210143188671;10024.498775061364;9492.620295188115;9512.09635084666;9517.952410238182;9605.615775368908;9670.516474176122 11:08:16 INFO - PROCESS | 1955 | __end_tp_report 11:08:16 INFO - PROCESS | 1955 | __start_cc_report 11:08:16 INFO - PROCESS | 1955 | _x_x_mozilla_cycle_collect,1509 11:08:16 INFO - PROCESS | 1955 | __end_cc_report 11:08:16 INFO - PROCESS | 1955 | __startTimestamp1465236496057__endTimestamp 11:08:16 INFO - PROCESS | 1955 | 11:08:16 INFO - PROCESS | 1955 | ------- Summary: start ------- 11:08:16 INFO - PROCESS | 1955 | Number of tests: 6 11:08:16 INFO - PROCESS | 1955 | 11:08:16 INFO - PROCESS | 1955 | [#0] dojo.html Cycles:165 Average:5675.21 Median:5875.06 stddev:653.50 (11.1%) stddev-sans-first:628.34 11:08:16 INFO - PROCESS | 1955 | Values: 3298.3 3307.3 3309.4 3310.7 3383.7 5586.0 5600.5 5608.8 5615.0 5620.5 5602.9 5655.5 5659.5 5662.6 5667.6 5052.7 5058.4 5136.3 5176.3 5217.0 5155.6 5182.3 5184.5 5192.1 5192.6 5090.2 5161.8 5177.5 5204.0 5219.0 4407.6 5128.8 5230.7 5251.6 5253.2 5021.6 5052.9 5066.0 5072.0 5166.6 5423.9 5489.4 5527.5 5541.2 5565.7 6144.7 6178.4 6190.4 6199.3 6201.3 5780.9 6071.1 6104.8 6219.2 6222.3 5717.6 5729.5 5742.3 5754.0 5773.9 6228.6 6235.0 6240.2 6243.6 6254.3 5937.7 6032.1 6085.6 6097.7 6104.0 6181.0 6190.4 6191.9 6203.1 6209.1 6157.6 6167.9 6175.6 6180.6 6184.0 6024.5 6055.6 6093.2 6138.4 6182.1 4831.9 4840.4 4851.7 4871.9 4874.7 4365.7 4379.3 4383.8 4385.6 4413.6 5604.1 5613.0 5613.6 5615.5 5618.9 6085.9 6163.7 6184.8 6197.9 6221.3 5602.5 5619.2 5623.1 5624.2 5627.0 4452.5 5302.7 5355.6 5358.6 5414.2 5294.8 6248.3 6263.8 6454.2 6459.2 6186.6 6211.8 6238.5 6247.1 6255.6 6205.3 6212.4 6315.4 6331.2 6501.1 6049.6 6091.5 6134.5 6134.5 6134.5 6171.9 6175.3 6195.0 6205.5 6206.1 5866.3 5899.3 6037.9 6074.6 6121.1 6119.1 6126.2 6138.3 6143.7 6162.6 5970.5 5973.8 5976.6 5977.8 5995.3 5966.6 6030.8 6042.3 6081.5 6114.3 5816.9 5849.1 5856.0 5862.8 5875.1 11:08:16 INFO - PROCESS | 1955 | 11:08:16 INFO - PROCESS | 1955 | [#1] ext.html Cycles:180 Average:66038.52 Median:3562.47 stddev:137946.74 (3872.2%) stddev-sans-first:138245.97 11:08:16 INFO - PROCESS | 1955 | Values: 508.2 510.4 537.3 588.1 605.5 28222.6 31410.2 38750.4 39311.4 39562.8 35322.9 42223.6 42566.8 42886.6 42917.4 9480.1 9797.0 9800.1 9811.0 9820.6 8589.3 8758.4 11554.0 11766.6 11780.0 11420.4 11497.1 11498.8 11499.7 11516.6 8579.9 11563.7 11566.9 11571.8 11587.9 11582.7 11789.5 11793.9 11805.3 11810.6 31789.4 38339.6 38577.4 38716.0 38720.0 3244.2 3345.7 3545.5 3579.4 3818.1 27555.9 27775.3 28275.7 28627.9 28634.7 2709.4 2731.3 2780.4 2796.9 2843.2 482515.0 483086.0 483196.0 483846.0 484426.0 61109.4 62396.7 62401.8 62479.0 62608.4 69147.0 69791.3 70001.0 70021.6 70053.6 17424.6 17606.9 17651.0 17669.8 17674.6 12623.0 12739.8 13351.9 13420.6 13434.9 17072.2 19984.6 20091.8 20103.4 20134.8 8083.8 8234.9 8238.2 8280.8 8298.0 935.1 1001.4 1037.2 1041.1 1093.7 29658.9 29719.0 30198.5 30307.5 30319.2 1070.6 1119.2 1210.1 1236.4 1262.2 15230.0 15280.5 15512.2 15630.3 15647.1 597681.0 612523.9 612764.0 612989.0 613614.0 56258.4 56300.7 61961.0 62031.0 62116.8 1313.4 1416.5 1495.5 1553.8 1561.4 26716.9 29016.9 29724.9 30195.4 30323.8 48961.5 49255.8 49574.8 49599.8 49645.5 443219.0 446995.8 447200.0 447240.0 447438.0 23763.8 23805.9 44243.0 44959.6 45750.5 45993.5 46185.5 46424.0 46615.8 47525.0 37422.4 37611.7 37622.4 37658.2 38371.8 34056.5 36912.8 37585.8 39245.8 39382.8 37935.4 38357.6 38774.4 39096.6 39635.0 34163.5 34365.7 34389.1 34931.8 35076.0 23464.6 23728.3 25430.6 25557.9 25921.4 11:08:16 INFO - PROCESS | 1955 | 11:08:16 INFO - PROCESS | 1955 | [#2] jquery.html Cycles:180 Average:35261.26 Median:14182.08 stddev:104817.85 (739.1%) stddev-sans-first:105079.21 11:08:16 INFO - PROCESS | 1955 | Values: 406.3 408.8 410.2 410.7 421.5 13858.8 20218.3 20674.4 20835.9 20871.9 15564.8 16138.3 20327.5 20640.1 20664.8 5575.9 5681.1 5855.6 5856.2 5868.3 4107.8 5744.2 5833.6 5839.7 5841.4 6110.1 6385.7 6404.5 6405.2 6422.5 6260.9 6364.9 6371.9 6377.6 6378.0 5491.5 5602.2 5635.1 5636.6 5643.4 23493.4 24857.4 25279.2 25587.0 25641.9 2023.9 2173.3 2261.8 2303.2 2722.3 9517.6 9730.5 9824.4 10010.7 13700.6 1533.7 1593.8 1805.8 1843.4 1873.1 107666.0 113077.4 117144.0 118046.0 122633.0 9710.6 11499.3 11715.2 11719.4 11740.4 14757.6 14819.6 14820.3 14840.6 14850.7 7561.2 7826.7 8749.7 8777.5 8812.9 8861.4 8883.7 8926.0 9008.4 9022.4 4546.0 4607.8 4620.5 4670.2 4678.5 1968.3 1979.2 1979.8 1992.3 2017.4 894.3 914.6 986.1 999.5 1023.8 19770.6 20196.3 20385.4 20502.9 20521.1 958.2 958.7 982.7 990.1 1033.3 9009.9 10843.3 10883.2 10892.9 10937.8 621972.0 627534.0 635257.0 635315.0 635483.0 119508.8 120509.4 120645.4 120650.0 122543.0 1004.9 1012.2 1023.5 1026.1 1028.2 17847.8 17982.5 18145.6 18157.5 18466.4 51020.7 51563.7 53256.2 53551.7 54248.5 69248.7 72406.6 72410.6 74009.0 75045.6 8005.7 8035.4 8120.8 8263.3 8413.3 10364.4 11389.8 11892.5 12104.9 12383.8 5528.1 7269.0 10060.1 10598.2 10652.7 10137.2 10255.4 10386.5 10643.9 10670.7 8279.3 10346.3 10662.9 10997.0 11017.8 7543.7 7821.3 7911.1 7911.9 8322.1 7958.9 9998.5 10318.1 10656.1 10695.1 11:08:16 INFO - PROCESS | 1955 | 11:08:16 INFO - PROCESS | 1955 | [#3] mootools.html Cycles:180 Average:5467.22 Median:5193.23 stddev:2950.64 (56.8%) stddev-sans-first:2943.22 11:08:16 INFO - PROCESS | 1955 | Values: 1417.8 1526.4 1546.1 1773.4 1931.3 7616.6 8200.7 8356.4 8357.0 8370.7 8139.0 8402.3 8410.3 8419.8 8421.5 2446.6 2475.1 2478.3 2479.6 2480.1 2400.1 2482.5 2486.2 2487.3 2489.7 2083.0 2441.3 2464.5 2482.5 2488.3 2444.7 2465.5 2466.6 2481.4 2486.2 2418.2 2455.7 2465.9 2472.1 2480.3 8180.1 8399.8 8452.7 8455.1 8464.2 5160.6 5311.7 5342.0 5345.5 5355.1 3506.7 3566.6 3626.7 3632.7 3665.9 1043.7 1064.5 1067.0 1067.1 1067.8 11068.4 11175.7 11309.8 11321.6 11332.0 4484.1 4507.0 4598.9 4619.0 4624.9 7931.4 8182.6 9016.7 9035.9 9092.9 5221.5 5228.4 5228.5 5256.9 5271.8 4373.7 4478.0 4479.8 4480.0 4913.9 3655.9 3831.0 3907.1 3907.9 3909.3 1119.8 1151.9 1208.8 1229.2 1237.6 911.6 924.2 951.5 955.8 967.1 5165.0 5736.9 5890.6 5960.6 6019.8 903.8 929.7 964.1 967.5 988.9 3201.8 3902.9 3940.0 4016.4 4020.0 9981.5 10072.6 10188.7 10267.0 10278.3 10035.9 10070.9 10152.6 10175.0 10184.3 6744.3 7539.5 7801.1 7851.7 7931.9 5661.5 5696.6 5738.0 5807.0 5854.5 8866.2 9099.2 9178.1 9247.9 9362.5 9314.7 9705.5 9719.7 9724.4 9734.0 6641.4 7202.2 7396.5 7472.8 7472.9 7469.4 7508.1 7518.3 7525.2 7809.1 6811.5 6960.0 7155.0 7245.7 7280.7 6827.4 6962.4 6998.6 7120.3 7138.1 5543.8 6446.0 6525.9 6565.4 6577.2 4711.9 4728.6 4839.2 4852.0 4909.8 4769.4 4840.5 4847.6 4875.4 4889.3 11:08:16 INFO - PROCESS | 1955 | 11:08:16 INFO - PROCESS | 1955 | [#4] prototype.html Cycles:180 Average:5787.55 Median:6498.29 stddev:1827.97 (28.1%) stddev-sans-first:1786.39 11:08:16 INFO - PROCESS | 1955 | Values: 316.9 321.9 323.6 328.0 328.5 6608.8 6613.7 6631.1 6645.0 6645.9 5809.4 6138.0 6544.9 6569.7 6589.5 4760.9 4847.2 4852.2 4943.6 4954.2 4870.9 4935.2 4941.8 4960.0 4964.0 4884.9 4894.3 4907.4 4914.2 4914.4 3906.3 4716.3 4805.2 4808.9 4839.3 4109.5 4182.5 4183.0 4199.0 4208.3 6301.0 6365.7 6399.2 6402.5 6422.3 5823.9 5824.0 5828.0 5833.0 5840.6 6831.7 7056.2 7076.0 7082.7 7086.5 4730.4 4743.5 4753.6 4757.8 4764.8 6691.5 6989.3 7420.1 7505.6 7513.7 4803.7 4813.2 4818.0 4822.8 4825.1 4763.3 4875.9 4890.1 4897.8 4901.1 4723.4 4936.3 5799.6 5813.7 5842.2 5763.9 6455.3 6532.1 7131.6 7136.7 1670.5 1711.2 1712.5 1713.4 1717.8 1078.6 1088.5 1107.6 1108.0 1111.5 6429.1 6623.9 6627.7 6629.7 6651.9 7104.9 7247.3 7271.7 7280.0 7293.2 5520.7 6587.5 6612.6 6639.3 6695.5 3893.9 3940.7 3942.5 3943.6 3959.1 6271.3 7450.6 7810.6 7829.5 7836.0 7595.9 7634.6 7650.7 7655.1 7656.0 6464.5 7639.8 7763.3 7804.3 7810.4 7418.5 7427.9 7431.6 7439.5 7443.4 5711.7 5986.6 7275.3 7282.8 7317.2 7695.4 7725.8 7751.6 7762.0 7773.5 4846.4 4850.9 4851.2 4854.0 4859.8 6009.4 7350.2 7372.9 7382.7 7393.9 6948.4 7079.6 7152.8 7154.6 7167.6 7065.2 7075.2 7101.1 7124.5 7176.4 6982.4 6987.4 6989.3 6991.3 7005.9 7133.2 7149.6 7156.2 7169.0 7169.5 6663.8 6765.3 6998.0 7021.9 7022.6 11:08:16 INFO - PROCESS | 1955 | 11:08:16 INFO - PROCESS | 1955 | [#5] yui.html Cycles:180 Average:18022.29 Median:618.38 stddev:25126.58 (4063.3%) stddev-sans-first:25163.03 11:08:16 INFO - PROCESS | 1955 | Values: 604.3 703.4 734.3 735.6 763.1 15006.3 15195.8 15198.5 16999.6 17374.3 15749.4 15846.8 15967.9 15974.0 15986.4 746.6 759.8 925.5 929.9 942.2 932.9 937.8 940.8 941.0 942.5 989.1 991.0 992.5 993.1 994.0 781.8 966.7 986.5 991.4 992.4 742.2 743.5 743.6 744.3 745.3 15066.9 15119.8 15124.3 15139.4 15139.9 12197.3 12198.6 12304.6 12395.4 12422.8 10130.6 10143.6 10215.9 10287.5 10311.5 3443.4 3479.7 3588.3 3650.6 3734.7 64057.0 69670.7 75786.0 76629.6 76675.6 9187.6 9213.4 9243.0 9473.0 9499.7 20278.5 20397.5 20399.1 20440.3 20448.5 7172.4 7177.9 7224.1 7341.9 7590.3 3967.0 4033.6 4113.6 4137.9 4305.8 3524.8 3571.9 3573.2 3579.7 3579.8 592.2 619.1 636.9 641.2 642.9 527.2 589.0 608.9 614.8 629.7 7856.8 8192.2 8219.3 8233.8 8329.8 508.8 584.6 585.8 603.7 617.7 3741.2 3836.8 3846.8 3883.4 3897.2 88850.1 90237.0 90457.0 90588.1 92802.5 79161.6 79842.4 80166.0 80393.0 82295.2 70154.0 71214.3 72470.0 72529.0 73909.6 6409.4 7710.5 7751.9 7757.1 8067.4 48282.3 48573.3 48829.5 49020.3 49075.5 53221.0 64502.0 65876.0 67986.7 71204.3 10888.0 11000.7 11064.8 11228.7 11405.0 11849.1 11870.3 11937.9 12439.9 12683.2 8491.7 10310.8 10314.7 10798.7 11991.0 11457.5 11547.2 11729.2 11783.3 11893.4 9117.5 11095.3 11414.7 11593.3 11817.4 9387.8 9441.2 9762.2 9873.2 10024.5 9492.6 9512.1 9518.0 9605.6 9670.5 11:08:16 INFO - PROCESS | 1955 | -------- Summary: end -------- 11:08:16 INFO - PROCESS | 1955 | 11:08:16 INFO - TEST-INFO | 1955: exit 0 11:08:16 INFO - TEST-OK | dromaeo_css | took 1114978ms 11:08:16 INFO - TEST-START | kraken 11:08:16 INFO - operating with platform_type : mac_ 11:08:16 INFO - Initialising browser for kraken test... 11:08:16 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmpkqTGhA/profile http://localhost:57384/getInfo.html) 11:08:18 INFO - PROCESS | 1960 | __metrics Screen width/height:1600/1200 11:08:18 INFO - PROCESS | 1960 | colorDepth:24 11:08:18 INFO - PROCESS | 1960 | Browser inner width/height: 1024/689 11:08:18 INFO - PROCESS | 1960 | __metrics 11:08:18 INFO - PROCESS | 1960 | 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" 11:08:18 ERROR - PROCESS | 1960 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:08:19 INFO - TEST-INFO | 1960: exit 0 11:08:19 INFO - Browser initialized. 11:08:19 INFO - Running cycle 1/1 for kraken test... 11:08:19 INFO - TEST-INFO | started process 1962 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmpkqTGhA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:08:31 INFO - PROCESS | 1962 | RSS: Main: 211427328 11:08:31 INFO - PROCESS | 1962 | 11:08:33 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:57384/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57384/tests/kraken/driver.html?testName=audio-beat-detection) 11:08:34 INFO - PROCESS | 1962 | RSS: Main: 274919424 11:08:34 INFO - PROCESS | 1962 | 11:08:37 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:57384/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57384/tests/kraken/driver.html?testName=audio-dft) 11:08:38 INFO - PROCESS | 1962 | RSS: Main: 289894400 11:08:38 INFO - PROCESS | 1962 | 11:08:41 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:57384/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57384/tests/kraken/driver.html?testName=audio-fft) 11:08:41 INFO - PROCESS | 1962 | RSS: Main: 374800384 11:08:41 INFO - PROCESS | 1962 | 11:08:43 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:57384/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57384/tests/kraken/driver.html?testName=audio-oscillator) 11:08:44 INFO - PROCESS | 1962 | RSS: Main: 280707072 11:08:44 INFO - PROCESS | 1962 | 11:08:46 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:57384/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57384/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:08:47 INFO - PROCESS | 1962 | RSS: Main: 282419200 11:08:47 INFO - PROCESS | 1962 | 11:08:49 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:57384/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57384/tests/kraken/driver.html?testName=imaging-darkroom) 11:08:50 INFO - PROCESS | 1962 | RSS: Main: 321363968 11:08:50 INFO - PROCESS | 1962 | 11:08:53 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:57384/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57384/tests/kraken/driver.html?testName=imaging-desaturate) 11:08:54 INFO - PROCESS | 1962 | RSS: Main: 321232896 11:08:54 INFO - PROCESS | 1962 | 11:08:56 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:57384/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57384/tests/kraken/driver.html?testName=json-parse-financial) 11:08:57 INFO - PROCESS | 1962 | RSS: Main: 318865408 11:08:57 INFO - PROCESS | 1962 | 11:08:59 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:57384/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57384/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:08:59 INFO - PROCESS | 1962 | RSS: Main: 311013376 11:08:59 INFO - PROCESS | 1962 | 11:09:01 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:57384/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57384/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:09:02 INFO - PROCESS | 1962 | RSS: Main: 316968960 11:09:02 INFO - PROCESS | 1962 | 11:09:04 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:57384/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57384/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:09:05 INFO - PROCESS | 1962 | RSS: Main: 322023424 11:09:05 INFO - PROCESS | 1962 | 11:09:07 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:57384/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57384/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:09:08 INFO - PROCESS | 1962 | RSS: Main: 328790016 11:09:08 INFO - PROCESS | 1962 | 11:09:11 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:57384/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57384/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:09:11 INFO - PROCESS | 1962 | RSS: Main: 325881856 11:09:11 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:57384/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57384/tests/kraken/driver.html?testName=ai-astar) 11:09:13 INFO - PROCESS | 1962 | RSS: Main: 387014656 11:09:13 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | __start_tp_report 11:09:13 INFO - PROCESS | 1962 | _x_x_mozilla_page_load 11:09:13 INFO - PROCESS | 1962 | _x_x_mozilla_page_load_details 11:09:13 INFO - PROCESS | 1962 | |i|pagename|runs| 11:09:13 INFO - PROCESS | 1962 | |0;ai-astar;115;115;121;114;113;113;116;116;116;136 11:09:13 INFO - PROCESS | 1962 | |1;audio-beat-detection;157;123;144;165;119;143;119;156;155;120 11:09:13 INFO - PROCESS | 1962 | |2;audio-dft;187;190;185;185;186;184;186;185;184;184 11:09:13 INFO - PROCESS | 1962 | |3;audio-fft;71;79;73;155;56;54;56;66;132;56 11:09:13 INFO - PROCESS | 1962 | |4;audio-oscillator;92;90;147;77;78;78;129;80;77;130 11:09:13 INFO - PROCESS | 1962 | |5;imaging-gaussian-blur;89;84;88;85;87;84;88;85;88;84 11:09:13 INFO - PROCESS | 1962 | |6;imaging-darkroom;92;93;94;93;93;94;93;94;104;150 11:09:13 INFO - PROCESS | 1962 | |7;imaging-desaturate;86;83;86;84;86;83;86;84;86;91 11:09:13 INFO - PROCESS | 1962 | |8;json-parse-financial;60;64;63;62;62;63;64;82;59;57 11:09:13 INFO - PROCESS | 1962 | |9;json-stringify-tinderbox;62;67;64;81;75;71;66;68;72;99 11:09:13 INFO - PROCESS | 1962 | |10;stanford-crypto-aes;82;82;81;81;82;81;80;80;82;86 11:09:13 INFO - PROCESS | 1962 | |11;stanford-crypto-ccm;132;125;129;128;126;130;130;127;150;143 11:09:13 INFO - PROCESS | 1962 | |12;stanford-crypto-pbkdf2;166;163;168;160;166;164;165;165;164;168 11:09:13 INFO - PROCESS | 1962 | |13;stanford-crypto-sha256-iterative;67;68;68;67;66;67;68;68;67;67 11:09:13 INFO - PROCESS | 1962 | __end_tp_report 11:09:13 INFO - PROCESS | 1962 | __start_cc_report 11:09:13 INFO - PROCESS | 1962 | _x_x_mozilla_cycle_collect,1968 11:09:13 INFO - PROCESS | 1962 | __end_cc_report 11:09:13 INFO - PROCESS | 1962 | __startTimestamp1465236553698__endTimestamp 11:09:13 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | ------- Summary: start ------- 11:09:13 INFO - PROCESS | 1962 | Number of tests: 14 11:09:13 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | [#0] ai-astar Cycles:10 Average:117.50 Median:116.00 stddev:6.88 (5.9%) stddev-sans-first:7.24 11:09:13 INFO - PROCESS | 1962 | Values: 115.0 115.0 121.0 114.0 113.0 113.0 116.0 116.0 116.0 136.0 11:09:13 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:149.50 stddev:18.23 (12.2%) stddev-sans-first:18.28 11:09:13 INFO - PROCESS | 1962 | Values: 157.0 123.0 144.0 165.0 119.0 143.0 119.0 156.0 155.0 120.0 11:09:13 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | [#2] audio-dft Cycles:10 Average:185.60 Median:185.50 stddev:1.84 (1.0%) stddev-sans-first:1.88 11:09:13 INFO - PROCESS | 1962 | Values: 187.0 190.0 185.0 185.0 186.0 184.0 186.0 185.0 184.0 184.0 11:09:13 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | [#3] audio-fft Cycles:10 Average:79.80 Median:61.00 stddev:35.05 (57.5%) stddev-sans-first:37.04 11:09:13 INFO - PROCESS | 1962 | Values: 71.0 79.0 73.0 155.0 56.0 54.0 56.0 66.0 132.0 56.0 11:09:13 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:78.00 stddev:26.86 (34.4%) stddev-sans-first:28.40 11:09:13 INFO - PROCESS | 1962 | Values: 92.0 90.0 147.0 77.0 78.0 78.0 129.0 80.0 77.0 130.0 11:09:13 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.50 stddev:1.99 (2.3%) stddev-sans-first:1.83 11:09:13 INFO - PROCESS | 1962 | Values: 89.0 84.0 88.0 85.0 87.0 84.0 88.0 85.0 88.0 84.0 11:09:13 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | [#6] imaging-darkroom Cycles:10 Average:100.00 Median:93.00 stddev:17.90 (19.2%) stddev-sans-first:18.75 11:09:13 INFO - PROCESS | 1962 | Values: 92.0 93.0 94.0 93.0 93.0 94.0 93.0 94.0 104.0 150.0 11:09:13 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:86.00 stddev:2.32 (2.7%) stddev-sans-first:2.46 11:09:13 INFO - PROCESS | 1962 | Values: 86.0 83.0 86.0 84.0 86.0 83.0 86.0 84.0 86.0 91.0 11:09:13 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:63.00 stddev:6.85 (10.9%) stddev-sans-first:7.14 11:09:13 INFO - PROCESS | 1962 | Values: 60.0 64.0 63.0 62.0 62.0 63.0 64.0 82.0 59.0 57.0 11:09:13 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:71.50 stddev:10.84 (15.2%) stddev-sans-first:10.82 11:09:13 INFO - PROCESS | 1962 | Values: 62.0 67.0 64.0 81.0 75.0 71.0 66.0 68.0 72.0 99.0 11:09:13 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | [#10] stanford-crypto-aes Cycles:10 Average:81.70 Median:82.00 stddev:1.70 (2.1%) stddev-sans-first:1.80 11:09:13 INFO - PROCESS | 1962 | Values: 82.0 82.0 81.0 81.0 82.0 81.0 80.0 80.0 82.0 86.0 11:09:13 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | [#11] stanford-crypto-ccm Cycles:10 Average:132.00 Median:130.00 stddev:8.08 (6.2%) stddev-sans-first:8.57 11:09:13 INFO - PROCESS | 1962 | Values: 132.0 125.0 129.0 128.0 126.0 130.0 130.0 127.0 150.0 143.0 11:09:13 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.90 Median:165.50 stddev:2.38 (1.4%) stddev-sans-first:2.49 11:09:13 INFO - PROCESS | 1962 | Values: 166.0 163.0 168.0 160.0 166.0 164.0 165.0 165.0 164.0 168.0 11:09:13 INFO - PROCESS | 1962 | 11:09:13 INFO - PROCESS | 1962 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:0.67 (1.0%) stddev-sans-first:0.71 11:09:13 INFO - PROCESS | 1962 | Values: 67.0 68.0 68.0 67.0 66.0 67.0 68.0 68.0 67.0 67.0 11:09:13 INFO - PROCESS | 1962 | -------- Summary: end -------- 11:09:13 INFO - PROCESS | 1962 | 11:09:14 INFO - TEST-INFO | 1962: exit 0 11:09:14 INFO - TEST-OK | kraken | took 57824ms 11:09:14 INFO - SUITE-END | took 1172s 11:09:14 INFO - Completed test suite (00:19:33) 11:09:14 INFO - javascript benchmark 11:09:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3298.290867463495, 3307.338532293541, 3309.3877632637973, 3310.652381499941, 3383.7123844473217, 5585.994520986221, 5600.467955544227, 5608.80369187078, 5615.017371959906, 5620.494155526002, 5602.915956260659, 5655.462731040555, 5659.462351076651, 5662.631928924617, 5667.603267771247, 5052.671576347544, 5058.367704036996, 5136.255242989773, 5176.327077479922, 5217.008768334023, 5155.613329000321, 5182.274481572583, 5184.455632158626, 5192.117340052181, 5192.610554208431, 5090.236464530331, 5161.845144645647, 5177.456367081459, 5204.0, 5219.0, 4407.581279778416, 5128.769205385748, 5230.660007099565, 5251.55361794248, 5253.185756207756, 5021.59827213822, 5052.9242061369105, 5066.0, 5072.0, 5166.586673066184, 5423.945760542383, 5489.368722596872, 5527.53015993641, 5541.224228607998, 5565.693886836222, 6144.723487443076, 6178.38216178383, 6190.41191086846, 6199.287081985584, 6201.255849298113, 5780.855478613068, 6071.122280061436, 6104.78905871602, 6219.222597175353, 6222.3466536013975, 5717.628354156925, 5729.54163666914, 5742.310922689304, 5753.971230143906, 5773.855653608652, 6228.564000520011, 6234.9688251558455, 6240.188775459162, 6243.562950593415, 6254.34329395416, 5937.732802023919, 6032.065029920369, 6085.604435711664, 6097.725602347905, 6103.992841181244, 6181.0, 6190.442860142609, 6191.876162476815, 6203.131561581382, 6209.0996805462955, 6157.630542167484, 6167.938320616737, 6175.62946223228, 6180.6291622502795, 6184.041473571604, 6024.518038556905, 6055.636661800218, 6093.238345206849, 6138.388398386059, 6182.1345011698395, 4831.9275210871865, 4840.419149702058, 4851.684640498356, 4871.853844384674, 4874.707517548887, 4365.672574556921, 4379.277419225856, 4383.7588932609015, 4385.649148068147, 4413.602775750198, 5604.075327570905, 5612.961602103543, 5613.578981576398, 5615.522680572116, 5618.887622247614, 6085.939140608626, 6163.660998645118, 6184.752609895554, 6197.907031394533, 6221.253449586078, 5602.467765562265, 5619.157126430986, 5623.128415095667, 5624.156376543469, 5626.97186514073, 4452.510223875371, 5302.734863256822, 5355.598330125178, 5358.571314294847, 5414.160805075219, 5294.841154765363, 6248.343923888017, 6263.812085637438, 6454.225492940877, 6459.192600924885, 6186.597371445597, 6211.751529938751, 6238.532110091671, 6247.0629405589525, 6255.593386429868, 6205.317415084427, 6212.4408803207925, 6315.431611155018, 6331.176946996861, 6501.122348482894, 6049.637021778707, 6091.451769340603, 6134.478569321657, 6134.478569321657, 6134.509239260938, 6171.949211970015, 6175.2589689237575, 6195.0, 6205.503559715302, 6206.131141640264, 5866.2960444746905, 5899.292084949833, 6037.879242415215, 6074.57477976555, 6121.081837724377, 6119.051547010221, 6126.172966649446, 6138.324784273816, 6143.6928153593035, 6162.630242185483, 5970.462658360769, 5973.790917318047, 5976.641401515923, 5977.820665380045, 5995.340512543704, 5966.612170208922, 6030.7889223876955, 6042.305134909541, 6081.452669259788, 6114.296855861633, 5816.854578635568, 5849.1226316052935, 5856.0, 5862.798126383996, 5875.059990401515], "value": 5632.522501100265, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.15138718340376, 510.40027967138604, 537.2612657595806, 588.0943347245243, 605.5337390209537, 28222.576661350096, 31410.21474463137, 38750.41874371883, 39311.41032884502, 39562.8021859891, 35322.940311790626, 42223.57776422235, 42566.787166064205, 42886.57113428872, 42917.35623965643, 9480.146786789188, 9796.971318011612, 9800.117989380955, 9811.0, 9820.558074886612, 8589.312854971618, 8758.386912916098, 11554.017908477748, 11766.647000589954, 11780.0, 11420.371879546621, 11497.080233581293, 11498.827517587242, 11499.712507187303, 11516.596919107833, 8579.871301930476, 11563.710907227303, 11566.942165289203, 11571.768564628745, 11587.884121158764, 11582.6525204244, 11789.469473873696, 11793.938545530944, 11805.291682498992, 11810.586629468013, 31789.36421271585, 38339.616603833885, 38577.421338679946, 38716.0, 38720.0, 3244.1727359523406, 3345.6654334566592, 3545.5213546171194, 3579.427291633326, 3818.064574179316, 27555.89776408941, 27775.305617359525, 28275.71724282772, 28627.856860715565, 28634.713652863415, 2709.4174752428125, 2731.3035176029985, 2780.3883145707914, 2796.9300767480977, 2843.1896909380935, 482515.0, 483086.0, 483196.0, 483845.9999999965, 484426.0, 61109.38890611037, 62396.68801656053, 62401.751964960444, 62479.0, 62608.37391626026, 69147.0, 69791.3020869795, 70001.0, 70021.64989175022, 70053.64973175102, 17424.564385890455, 17606.91196544009, 17651.0, 17669.823301767075, 17674.5581360467, 12623.0, 12739.808902866464, 13351.933240333736, 13420.597382078358, 13434.93282533581, 17072.207418220907, 19984.600307993758, 20091.799082009285, 20103.39689809308, 20134.791912485296, 8083.838323233503, 8234.876476852853, 8238.199012335834, 8280.751577452686, 8297.95851020753, 935.1256575102301, 1001.3641337750581, 1037.180627304421, 1041.0526420956892, 1093.7101668057971, 29658.851705741763, 29719.0, 30198.54702179469, 30307.545386819216, 30319.242018949262, 1070.6038765656756, 1119.1774046076127, 1210.0501106631252, 1236.3632729144497, 1262.211118051218, 15230.010049346756, 15280.465183718516, 15512.188790206978, 15630.296636651377, 15647.13940733248, 597681.0, 612523.9373803191, 612764.0, 612989.0, 613614.0, 56258.43741562532, 56300.71849640726, 61961.0, 62031.0, 62116.75766484645, 1313.4155300891202, 1416.5254639695818, 1495.53638372105, 1553.7591673290658, 1561.4222737586938, 26716.8664156678, 29016.854915725286, 29724.851375742983, 30195.396092078474, 30323.848380757954, 48961.51038489712, 49255.753721231165, 49574.75212623914, 49599.752001239765, 49645.503544964085, 443219.0, 446995.7650211858, 447200.0, 447240.0, 447438.0, 23763.762362376154, 23805.880970595037, 44243.0, 44959.55040449685, 45750.54249457463, 45993.54006459893, 46185.538144619466, 46424.0, 46615.83460413517, 47525.0, 37422.43866342062, 37611.68359107418, 37622.43566346452, 37658.24683506369, 38371.84884453471, 34056.48915266224, 36912.81543592265, 37585.81207093947, 39245.803770980965, 39382.803085984386, 37935.43096853494, 38357.6164238354, 38774.4183837237, 39096.609033910434, 39635.0, 34163.48754768631, 34365.65634343625, 34389.099754753755, 34931.82534087313, 35076.0, 23464.6480302799, 23728.339016268696, 25430.618540721534, 25557.872210639573, 25921.351966200997], "value": 20434.229471008475, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.3194149799087, 408.79151632667333, 410.19601580901434, 410.73918062030623, 421.54683715006377, 13858.792118118212, 20218.292359767413, 20674.379768606927, 20835.895820520895, 20871.895640521794, 15564.766528502054, 16138.273777680004, 20327.49181270469, 20640.07119679618, 20664.793352066474, 5575.8884822303635, 5681.119426488901, 5855.619384739978, 5856.150858125569, 5868.325142608591, 4107.7535347879075, 5744.16709577111, 5833.620814647055, 5839.678817665027, 5841.386654401291, 6110.1140334651445, 6385.744570217185, 6404.519661025419, 6405.167328247321, 6422.454091402236, 6260.874782504325, 6364.872702545947, 6371.904421433682, 6377.617342959414, 6378.011408231709, 5491.478309560625, 5602.215689803431, 5635.126555383922, 5636.605437619368, 5643.351014633328, 23493.41266468335, 24857.378565535826, 25279.248088042463, 25587.0, 25641.871790641115, 2023.9494012649654, 2173.2936795541355, 2261.7851304126084, 2303.1593468384085, 2722.2922040269473, 9517.619295228182, 9730.464824434654, 9824.361416507903, 10010.699679009642, 13700.588982330546, 1533.7469317562588, 1593.7848390467254, 1805.7652505174374, 1843.364039406416, 1873.0821897270375, 107666.0, 113077.43461282723, 117144.0, 118046.0, 122633.0, 9710.563024663907, 11499.310041397375, 11715.1799374043, 11719.355435450962, 11740.354280514483, 14757.557273281818, 14819.629509262355, 14820.3330850112, 14840.62898427548, 14850.702985940437, 7561.243875612395, 7826.686932522635, 8749.72507148146, 8777.473351598925, 8812.911870881337, 8861.424007439497, 8883.73348799537, 8925.973513046083, 9008.41445306053, 9022.368434209542, 4545.954540454619, 4607.815687372467, 4620.53794620542, 4670.229412147026, 4678.461976872703, 1968.3209292793963, 1979.166149707948, 1979.7822239553639, 1992.3026940570685, 2017.404865564662, 894.2801045158579, 914.6067191107887, 986.068165583531, 999.4652860719481, 1023.8157131716214, 19770.60458790816, 20196.29312974039, 20385.388438346872, 20502.897485512476, 20521.076551555216, 958.2382006305004, 958.6859133897461, 982.7297493189287, 990.0792263195298, 1033.3489901362093, 9009.909900901039, 10843.349399036084, 10883.23817332779, 10892.94553527243, 10937.83593246102, 621972.0, 627534.0, 635257.0, 635315.0, 635483.0, 119508.8049119515, 120509.39745301218, 120645.39677301557, 120650.0, 122543.0, 1004.9045340692622, 1012.2205901455837, 1023.4933707814679, 1026.1021606094666, 1028.2082796246698, 17847.750657453853, 17982.460526184237, 18145.637087258445, 18157.54606134857, 18466.35367762122, 51020.74489627528, 51563.74218128886, 53256.20115698345, 53551.73224133854, 54248.45751542434, 69248.6537567309, 72406.55186896338, 72410.55178896498, 74009.0, 75045.6247718758, 8005.679772809255, 8035.397345199251, 8120.75637730869, 8263.297619702393, 8413.326933845156, 10364.378137311785, 11389.829152562552, 11892.524299027942, 12104.878951210376, 12383.814242786546, 5528.143137813645, 7268.96365518169, 10060.0945914868, 10598.20513461509, 10652.680419587425, 10137.23970702215, 10255.435951022766, 10386.532606032595, 10643.935606439603, 10670.679879603624, 8279.254867061993, 10346.275760696679, 10662.89337106619, 10997.0, 11017.83473247886, 7543.698252069856, 7821.335186509211, 7911.090224624242, 7911.881321780293, 8322.126176751595, 7958.920410795818, 9998.500074996367, 10318.122959548522, 10656.147508199481, 10695.144388449062], "value": 9412.222421958279, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1417.82986041675, 1526.3513006972037, 1546.1418912503564, 1773.3793172389665, 1931.3336898769928, 7616.581088040156, 8200.671973121076, 8356.373272004594, 8357.038940521841, 8370.748877533668, 8138.959305203466, 8402.327813774898, 8410.327173826094, 8419.83160336793, 8421.536815475147, 2446.5718499262603, 2475.1337032038823, 2478.2565230430887, 2479.6404521344484, 2480.094765410621, 2400.147947478646, 2482.5407299649532, 2486.154707399484, 2487.3035550046015, 2489.7385774493687, 2083.0, 2441.292025312657, 2464.494778570389, 2482.491089326693, 2488.3032750829707, 2444.716409923543, 2465.543874383236, 2466.5930121530105, 2481.4292712676006, 2486.154707399484, 2418.201993342193, 2455.742147074567, 2465.938351541208, 2472.11004038546, 2480.2559232230437, 8180.059293181301, 8399.790005249857, 8452.704155354533, 8455.069942306342, 8464.19590138942, 5160.612954028463, 5311.654742441729, 5341.985022845685, 5345.4654534546235, 5355.116405793039, 3506.68439840413, 3566.6076731559506, 3626.746127771057, 3632.673059424638, 3665.9083522911874, 1043.6608102366686, 1064.4624464645383, 1066.951987160572, 1067.0716476665273, 1067.8024565455416, 11068.39123848188, 11175.664730058112, 11309.830352544719, 11321.603743868925, 11332.036776873976, 4484.1255955088445, 4506.977465112654, 4598.88502787433, 4619.0, 4624.907501849944, 7931.444798864024, 8182.590870456573, 9016.692579575925, 9035.909640903637, 9092.8636070459, 5221.477852214748, 5228.372595288513, 5228.477152284741, 5256.894862102737, 5271.789128434896, 4373.650107991353, 4477.977610111994, 4479.7984090716, 4480.036792089716, 4913.901721965541, 3655.8903232903053, 3830.9616903830606, 3907.081835768592, 3907.902302442405, 3909.2603791312595, 1119.7738476368363, 1151.9424028798694, 1208.7703336366062, 1229.2317301686446, 1237.5854088880146, 911.6080085563271, 924.177482040984, 951.5004622573205, 955.7515046088067, 967.0764419978904, 5164.974175129176, 5736.885262294731, 5890.587658863924, 5960.552958528127, 6019.826133903846, 903.8418276801665, 929.6606738540519, 964.0552258786429, 967.4823969176598, 988.9208863290919, 3201.7598680099086, 3902.902427439337, 3940.014996250937, 4016.397540368968, 4020.0150963013666, 9981.451020193814, 10072.647457338959, 10188.694339169688, 10266.973302669823, 10278.33190842593, 10035.949820250851, 10070.899291007143, 10152.593896244067, 10175.0, 10184.287099903084, 6744.325567443315, 7539.509931854412, 7801.102873169658, 7851.685932562747, 7931.920680793233, 5661.547076233809, 5696.609750061866, 5738.024535828836, 5807.041838096667, 5854.531637469077, 8866.246369058703, 9099.226565741987, 9178.128077832594, 9247.907520924704, 9362.485063321583, 9314.72055838326, 9705.5147242639, 9719.65981190655, 9724.416535007922, 9733.977932317288, 6641.402273795382, 7202.171750248788, 7396.482246242711, 7472.813179670552, 7472.850542989218, 7469.439792015729, 7508.136564295176, 7518.28576285252, 7525.247475252431, 7809.062912450542, 6811.523193376416, 6959.965200173967, 7154.964225179049, 7245.710171593288, 7280.708771649075, 6827.419669328163, 6962.443004559522, 6998.580085194701, 7120.287971202838, 7138.107736532934, 5543.805966791143, 6446.033095035782, 6525.9021114684265, 6565.376289252514, 6577.177852768404, 4711.882202944954, 4728.645351598712, 4839.177339852163, 4852.0, 4909.790594232538, 4769.403824521934, 4840.515948405272, 4847.563719265283, 4875.366202393808, 4889.266610008527], "value": 4468.948164837006, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.92552250221195, 321.8680341060164, 323.6407587577788, 327.970482656561, 328.4662423561712, 6608.801735947917, 6613.735450581977, 6631.071649969008, 6644.966775166124, 6645.900311495331, 5809.419058094199, 6138.017917133259, 6544.869102617945, 6569.737210511574, 6589.538732288742, 4760.85717428476, 4847.224444088946, 4852.199387101124, 4943.555080042795, 4954.207326827708, 4870.9269360959615, 4935.161022626145, 4941.827036053739, 4960.0, 4963.975180124095, 4884.852059765952, 4894.265860120975, 4907.4356449008465, 4914.213725803872, 4914.361133052698, 3906.2968665640033, 4716.339712440261, 4805.20714082176, 4808.903821923542, 4839.27410888366, 4109.465769449982, 4182.498100227992, 4182.9790851045855, 4198.971252043239, 4208.347706105558, 6301.0, 6365.745370185234, 6399.168108145957, 6402.48780097591, 6422.325655806119, 5823.941760582382, 5823.990723200489, 5828.0, 5833.0, 5840.591158618899, 6831.69257383414, 7056.15326160864, 7076.0, 7082.681279342442, 7086.503944723871, 4730.432348118214, 4743.525647435229, 4753.619710423158, 4757.762111894392, 4764.809407623691, 6691.464682825363, 6989.301069893021, 7420.146683131401, 7505.624718764095, 7513.699452021912, 4803.687760295569, 4813.181759100891, 4817.975910120427, 4822.758862056883, 4825.059113472909, 4763.261694437368, 4875.853724388274, 4890.095332363524, 4897.828575999894, 4901.09329773993, 4723.385959825201, 4936.308916751657, 5799.5650326225705, 5813.680247586339, 5842.182094506772, 5763.9135412968835, 6455.289918109004, 6532.138340573009, 7131.643417829087, 7136.678849451787, 1670.4571014420362, 1711.1700825099817, 1712.5204942616087, 1713.3574909408972, 1717.7595136680875, 1078.563181911327, 1088.5210507376728, 1107.6400169944638, 1107.9723006924892, 1111.4887151910211, 6429.067785171124, 6623.933760662332, 6627.734890604322, 6629.668516574248, 6651.86696266072, 7104.893426598605, 7247.347738703542, 7271.67277472515, 7279.963600182071, 7293.16128645202, 5520.696361700145, 6587.473002159816, 6612.603243805387, 6639.302873198245, 6695.451410752309, 3893.8831835044994, 3940.665043471337, 3942.5268967724055, 3943.585923478051, 3959.089409435846, 6271.310155882849, 7450.590217537979, 7810.57041862703, 7829.491083079582, 7835.960820195863, 7595.886061709079, 7634.580098094547, 7650.73222437212, 7655.0813902332075, 7656.0, 6464.450521705708, 7639.809004774814, 7763.301302882767, 7804.336631386395, 7810.375169986388, 7418.517796343221, 7427.925720742939, 7431.554106753612, 7439.479236453396, 7443.404527637667, 5711.685857277889, 5986.580939334205, 7275.308845659654, 7282.817929551592, 7317.195108538163, 7695.384369250334, 7725.806854828675, 7751.612419379121, 7761.9611901940125, 7773.533587984739, 4846.36997190363, 4850.902981940412, 4851.223804191453, 4854.0, 4859.781309840995, 6009.399060094131, 7350.228226036189, 7372.889406659012, 7382.704691812267, 7393.9260607393235, 6948.426215425211, 7079.584083183281, 7152.821179470555, 7154.606496642735, 7167.602317548028, 7065.1521817382245, 7075.150981882207, 7101.076860008166, 7124.536905101152, 7176.425885929218, 6982.416505892852, 6987.371136597731, 6989.33601307875, 6991.335823096798, 7005.894911576433, 7133.179684336368, 7149.571025738473, 7156.212816590275, 7168.964155179191, 7169.4622903281415, 6663.800085997428, 6765.2896445872475, 6998.0, 7021.859562808818, 7022.578645281299], "value": 5179.783402519606, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.314103492536, 703.3810246982655, 734.2547314475808, 735.6064505489564, 763.1376544504709, 15006.324715387806, 15195.84804151958, 15198.54404367868, 16999.57501062474, 17374.305027798873, 15749.448769293078, 15846.762298565502, 15967.920160399182, 15974.0, 15986.440474583393, 746.6080307838401, 759.7948553890446, 925.5141050948239, 929.934904556683, 942.194423767679, 932.9020452852454, 937.8218138553688, 940.7695114696909, 940.952952352383, 942.4769253064546, 989.1196834817018, 990.9603615855357, 992.4541502173812, 993.1458945307066, 994.0010289658883, 781.8319061401797, 966.6520052780993, 986.4919566423262, 991.3902949685963, 992.3698451483287, 742.2095468326215, 743.4647054121024, 743.6356185469135, 744.2520267131553, 745.2994185465645, 15066.945313828037, 15119.773203401957, 15124.319405626773, 15139.394424223017, 15139.924300378538, 12197.329146896916, 12198.57304994321, 12304.569340073143, 12395.380230988414, 12422.75154496905, 10130.594776208944, 10143.644972426004, 10215.89784102157, 10287.537060832206, 10311.535980880879, 3443.3974054540354, 3479.6694314040124, 3588.2644057968055, 3650.6349365063547, 3734.719896007783, 64057.0, 69670.65164674194, 75786.0, 76629.61685191594, 76675.61662191708, 9187.586558604879, 9213.355065145375, 9243.029481904303, 9472.952635236918, 9499.715008549754, 20278.493037674176, 20397.490062748253, 20399.08204130818, 20440.28459003928, 20448.488787780127, 7172.426205903619, 7177.928220717726, 7224.13310402755, 7341.853162936712, 7590.316871481593, 3967.0, 4033.616806403387, 4113.629773320416, 4137.8758637240935, 4305.784710764512, 3524.84138213781, 3571.910702232465, 3573.2317551726505, 3579.6957258633306, 3579.83890724918, 592.1737836631488, 619.0992106485054, 636.8567072408764, 641.2209165863405, 642.8714257148589, 527.2328761651787, 588.976440942366, 608.9269287685418, 614.775606903477, 629.7071861584385, 7856.803579910434, 8192.221738934792, 8219.260266576037, 8233.8353232935, 8329.833403331899, 508.7837668990664, 584.5937073733714, 585.8066837943469, 603.7494439807518, 617.6633734614646, 3741.214344987529, 3836.7889766062576, 3846.807659617008, 3883.37865941448, 3897.2205558887767, 88850.11149888548, 90237.0, 90457.0, 90588.09411905929, 92802.53598732098, 79161.60419197867, 79842.40315193664, 80166.0, 80393.0, 82295.17704822995, 70154.0, 71214.2878571218, 72470.0, 72529.0, 73909.63045184739, 6409.4231519163495, 7710.460267781202, 7751.8837217442915, 7757.1079325878245, 8067.435279530377, 48282.31011914566, 48573.27140092831, 48829.5117048825, 49020.26469602888, 49075.509244907094, 53221.0, 64502.0, 65876.0, 67986.66006669935, 71204.28795712184, 10888.0, 11000.724981875517, 11064.778704426028, 11228.719282017688, 11405.0, 11849.111316651113, 11870.287782733063, 11937.880621194025, 12439.875601243872, 12683.175593586388, 8491.702790402307, 10310.845337320095, 10314.742131446774, 10798.67603971882, 11991.040716742815, 11457.541698331974, 11547.249428787101, 11729.178957473236, 11783.293002419883, 11893.405329733305, 9117.498537580497, 11095.334279943228, 11414.714632134263, 11593.304401735923, 11817.409129543317, 9387.812243755223, 9441.15029647335, 9762.219022478326, 9873.210143188671, 10024.498775061364, 9492.620295188115, 9512.09635084666, 9517.952410238182, 9605.615775368908, 9670.516474176122], "value": 6883.09783487094, "unit": "score"}], "name": "dromaeo_css", "value": 7461.891681562135, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 121.0, 114.0, 113.0, 113.0, 116.0, 116.0, 116.0, 136.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 123.0, 144.0, 165.0, 119.0, 143.0, 119.0, 156.0, 155.0, 120.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 190.0, 185.0, 185.0, 186.0, 184.0, 186.0, 185.0, 184.0, 184.0], "value": 185.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 79.0, 73.0, 155.0, 56.0, 54.0, 56.0, 66.0, 132.0, 56.0], "value": 79.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 147.0, 77.0, 78.0, 78.0, 129.0, 80.0, 77.0, 130.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 88.0, 85.0, 87.0, 84.0, 88.0, 85.0, 88.0, 84.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 94.0, 93.0, 93.0, 94.0, 93.0, 94.0, 104.0, 150.0], "value": 100.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 84.0, 86.0, 83.0, 86.0, 84.0, 86.0, 91.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 63.0, 62.0, 62.0, 63.0, 64.0, 82.0, 59.0, 57.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 67.0, 64.0, 81.0, 75.0, 71.0, 66.0, 68.0, 72.0, 99.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 81.0, 81.0, 82.0, 81.0, 80.0, 80.0, 82.0, 86.0], "value": 81.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 125.0, 129.0, 128.0, 126.0, 130.0, 130.0, 127.0, 150.0, 143.0], "value": 132.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 168.0, 160.0, 166.0, 164.0, 165.0, 165.0, 164.0, 168.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 67.0, 66.0, 67.0, 68.0, 68.0, 67.0, 67.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1474.5, "alertThreshold": 2.0}]} 11:09:14 INFO - Return code: 0 11:09:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:09:15 ERROR - # TBPL SUCCESS # 11:09:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:09:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:09:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:09:15 INFO - 2016-06-06 11:09:15.022 screenresolution[1966:21535] starting screenresolution argv=screenresolution get 11:09:15 INFO - 2016-06-06 11:09:15.025 screenresolution[1966:21535] Display 0: 1600x1200x32@60 11:09:15 INFO - 2016-06-06 11:09:15.031 screenresolution[1967:21538] starting screenresolution argv=screenresolution list 11:09:15 INFO - Available Modes on Display 0 11:09:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:09:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:09:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:09:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:09:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:09:15 INFO - Intel Iris: 11:09:15 INFO - Chipset Model: Intel Iris 11:09:15 INFO - Type: GPU 11:09:15 INFO - Bus: Built-In 11:09:15 INFO - VRAM (Dynamic, Max): 1536 MB 11:09:15 INFO - Vendor: Intel (0x8086) 11:09:15 INFO - Device ID: 0x0a2e 11:09:15 INFO - Revision ID: 0x0009 11:09:15 INFO - Displays: 11:09:15 INFO - Display: 11:09:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:09:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:09:15 INFO - Display Serial Number: 1600x1200 60 11:09:15 INFO - Main Display: Yes 11:09:15 INFO - Mirror: Off 11:09:15 INFO - Online: Yes 11:09:15 INFO - Rotation: Supported 11:09:15 INFO - Return code: 0 11:09:15 INFO - Running post-action listener: _resource_record_post_action 11:09:15 INFO - Running post-run listener: _resource_record_post_run 11:09:16 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 360448; Write bytes: 656162304; Read time: 8; Write time: 2010 11:09:16 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 342696448; Write bytes: 336848896; Read time: 26409; Write time: 660 11:09:16 INFO - run-tests - Wall time: 1175s; CPU: 52.0%; Read bytes: 360448; Write bytes: 299890176; Read time: 8; Write time: 1295 11:09:16 INFO - Running post-run listener: _upload_blobber_files 11:09:16 INFO - Blob upload gear active. 11:09:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:09:16 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:09:16 INFO - (blobuploader) - INFO - Open directory for files ... 11:09:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:09:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:09:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:09:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:09:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:09:17 INFO - (blobuploader) - INFO - Done attempting. 11:09:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:09:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:09:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:09:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:09:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:09:18 INFO - (blobuploader) - INFO - Done attempting. 11:09:18 INFO - (blobuploader) - INFO - Iteration through files over. 11:09:18 INFO - Return code: 0 11:09:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:09:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:09:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/05d8b93d125443f9b988cad3c1c8d34eca2cb531e7734585ecfbffa225deebfd2a6341559437498852f6fc6d6c583d9319e11d9548640c4bcccf7f94f3d8d237", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aeffecf56ff7255a5ceb241b4721750e560493b315ab04fa8865543ddce763d4f8f9da96250bb70b9fd32238ff1497a4db7ad7117202f1772b211c3c86b3e883"} 11:09:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:09:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:09:18 INFO - Contents: 11:09:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/05d8b93d125443f9b988cad3c1c8d34eca2cb531e7734585ecfbffa225deebfd2a6341559437498852f6fc6d6c583d9319e11d9548640c4bcccf7f94f3d8d237", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aeffecf56ff7255a5ceb241b4721750e560493b315ab04fa8865543ddce763d4f8f9da96250bb70b9fd32238ff1497a4db7ad7117202f1772b211c3c86b3e883"} 11:09:18 INFO - Running post-run listener: copy_logs_to_upload_dir 11:09:18 INFO - Copying logs to upload dir... 11:09:18 INFO - mkdir: /builds/slave/test/build/upload/logs 11:09:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.873244 ========= master_lag: 4.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-06-06 11:09:22.799055) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 11:09:22.804058) ========= 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.KBMNcLRUqf/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.0hriJA4zPA/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/05d8b93d125443f9b988cad3c1c8d34eca2cb531e7734585ecfbffa225deebfd2a6341559437498852f6fc6d6c583d9319e11d9548640c4bcccf7f94f3d8d237", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aeffecf56ff7255a5ceb241b4721750e560493b315ab04fa8865543ddce763d4f8f9da96250bb70b9fd32238ff1497a4db7ad7117202f1772b211c3c86b3e883"} build_url:https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010977 build_url: 'https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/05d8b93d125443f9b988cad3c1c8d34eca2cb531e7734585ecfbffa225deebfd2a6341559437498852f6fc6d6c583d9319e11d9548640c4bcccf7f94f3d8d237", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aeffecf56ff7255a5ceb241b4721750e560493b315ab04fa8865543ddce763d4f8f9da96250bb70b9fd32238ff1497a4db7ad7117202f1772b211c3c86b3e883"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 11:09:22.852132) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 11:09:22.852459) ========= 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.KBMNcLRUqf/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.0hriJA4zPA/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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.005330 ========= master_lag: 0.57 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 11:09:23.428596) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 11:09:23.428921) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 11:09:23.429466) ========= ========= Total master_lag: 4.80 =========