builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0147 starttime: 1469446207.02 results: success (0) buildid: 20160725022027 builduid: 06e7ff17d0754ca8bb48fa0689aa6a1f revision: f3d7abb885c267a7657e3b8ea06c18f76eb69641 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 04:30:07.015540) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 04:30:07.015998) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 04:30:07.016316) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 04:30:07.042173) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:30:07.042821) ========= 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.5qZgxSJFPS/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.OBTHWuiNLS/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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-07-25 04:30: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% 57.6M=0s 2016-07-25 04:30:07 (57.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097170 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:30:07.164835) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:30:07.165209) ========= 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.5qZgxSJFPS/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.OBTHWuiNLS/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.027598 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:30:07.215628) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:30:07.215984) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f3d7abb885c267a7657e3b8ea06c18f76eb69641 --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-beta --rev f3d7abb885c267a7657e3b8ea06c18f76eb69641 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5qZgxSJFPS/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.OBTHWuiNLS/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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-07-25 04:30:07,294 truncating revision to first 12 chars 2016-07-25 04:30:07,294 Setting DEBUG logging. 2016-07-25 04:30:07,294 attempt 1/10 2016-07-25 04:30:07,294 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f3d7abb885c2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-25 04:30:07,957 unpacking tar archive at: mozilla-beta-f3d7abb885c2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.922445 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:30:08.159299) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 04:30:08.159679) ========= script_repo_revision: f3d7abb885c267a7657e3b8ea06c18f76eb69641 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 04:30:08.160104) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 04:30:08.160453) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 04:30:08.179881) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-07-25 04:30:08.180514) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5qZgxSJFPS/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.OBTHWuiNLS/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 04:30:08 INFO - MultiFileLogger online at 20160725 04:30:08 in /builds/slave/test 04:30:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 04:30:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:30:08 INFO - {'append_to_log': False, 04:30:08 INFO - 'base_work_dir': '/builds/slave/test', 04:30:08 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:30:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:30:08 INFO - 'branch': 'Mozilla-Beta', 04:30:08 INFO - 'buildbot_json_path': 'buildprops.json', 04:30:08 INFO - 'config_files': ('talos/mac_config.py',), 04:30:08 INFO - 'default_actions': ('clobber', 04:30:08 INFO - 'read-buildbot-config', 04:30:08 INFO - 'download-and-extract', 04:30:08 INFO - 'populate-webroot', 04:30:08 INFO - 'create-virtualenv', 04:30:08 INFO - 'install', 04:30:08 INFO - 'run-tests'), 04:30:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:30:08 INFO - 'download_minidump_stackwalk': True, 04:30:08 INFO - 'download_symbols': 'ondemand', 04:30:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:30:08 INFO - 'tooltool.py': '/tools/tooltool.py', 04:30:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:30:08 INFO - '/tools/misc-python/virtualenv.py')}, 04:30:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:30:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:30:08 INFO - 'installer_path': 'installer.exe', 04:30:08 INFO - 'log_level': 'info', 04:30:08 INFO - 'log_name': 'talos', 04:30:08 INFO - 'log_to_console': True, 04:30:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:30:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:30:08 INFO - 'opt_config_files': (), 04:30:08 INFO - 'pip_index': False, 04:30:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:30:08 INFO - 'cmd': ('bash', 04:30:08 INFO - '-c', 04:30:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:30:08 INFO - 'enabled': True, 04:30:08 INFO - 'halt_on_failure': False, 04:30:08 INFO - 'name': 'check_screen_resolution'},), 04:30:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:30:08 INFO - 'cmd': ('bash', 04:30:08 INFO - '-c', 04:30:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:30:08 INFO - 'enabled': True, 04:30:08 INFO - 'halt_on_failure': False, 04:30:08 INFO - 'name': 'check_screen_resolution'},), 04:30:08 INFO - 'run_cmd_checks_enabled': True, 04:30:08 INFO - 'sps_profile': False, 04:30:08 INFO - 'sps_profile_interval': 0, 04:30:08 INFO - 'suite': 'dromaeojs-e10s', 04:30:08 INFO - 'system_bits': '32', 04:30:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:30:08 INFO - 'title': 't-yosemite-r7-0147', 04:30:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:30:08 INFO - 'use_talos_json': True, 04:30:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:30:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:30:08 INFO - 'work_dir': 'build'} 04:30:08 INFO - ##### 04:30:08 INFO - ##### Running clobber step. 04:30:08 INFO - ##### 04:30:08 INFO - Running pre-action listener: _resource_record_pre_action 04:30:08 INFO - Running main action method: clobber 04:30:08 INFO - rmtree: /builds/slave/test/build 04:30:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:30:10 INFO - Running post-action listener: _resource_record_post_action 04:30:10 INFO - ##### 04:30:10 INFO - ##### Running read-buildbot-config step. 04:30:10 INFO - ##### 04:30:10 INFO - Running pre-action listener: _resource_record_pre_action 04:30:10 INFO - Running main action method: read_buildbot_config 04:30:10 INFO - Using buildbot properties: 04:30:10 INFO - { 04:30:10 INFO - "project": "", 04:30:10 INFO - "product": "firefox", 04:30:10 INFO - "script_repo_revision": "production", 04:30:10 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 04:30:10 INFO - "repository": "", 04:30:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 04:30:10 INFO - "buildid": "20160725022027", 04:30:10 INFO - "pgo_build": "False", 04:30:10 INFO - "basedir": "/builds/slave/test", 04:30:10 INFO - "buildnumber": 33, 04:30:10 INFO - "slavename": "t-yosemite-r7-0147", 04:30:10 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:30:10 INFO - "platform": "yosemite_r7", 04:30:10 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 04:30:10 INFO - "branch": "mozilla-beta", 04:30:10 INFO - "repo_path": "releases/mozilla-beta", 04:30:10 INFO - "revision": "f3d7abb885c267a7657e3b8ea06c18f76eb69641", 04:30:10 INFO - "stage_platform": "macosx64", 04:30:10 INFO - "builduid": "06e7ff17d0754ca8bb48fa0689aa6a1f", 04:30:10 INFO - "slavebuilddir": "test" 04:30:10 INFO - } 04:30:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 04:30:10 INFO - Running post-action listener: _resource_record_post_action 04:30:10 INFO - ##### 04:30:10 INFO - ##### Running download-and-extract step. 04:30:10 INFO - ##### 04:30:10 INFO - Running pre-action listener: _resource_record_pre_action 04:30:10 INFO - Running main action method: download_and_extract 04:30:10 INFO - mkdir: /builds/slave/test/build/tests 04:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:10 INFO - https://queue.taskcluster.net/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:30:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 04:30:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 04:30:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 04:30:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 04:30:12 INFO - Downloaded 1165 bytes. 04:30:12 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 04:30:12 INFO - Using the following test package requirements: 04:30:12 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 04:30:12 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:30:12 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 04:30:12 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:30:12 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 04:30:12 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:30:12 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:30:12 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 04:30:12 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 04:30:12 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:30:12 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 04:30:12 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:30:12 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 04:30:12 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:30:12 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 04:30:12 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:30:12 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 04:30:12 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 04:30:12 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')]} 04:30:12 INFO - https://queue.taskcluster.net/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:30:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 04:30:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 04:30:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 04:30:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 04:30:13 INFO - Downloaded 18314917 bytes. 04:30:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:30:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:30:17 INFO - Return code: 0 04:30:17 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 04:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:17 INFO - https://queue.taskcluster.net/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:30:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 04:30:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 04:30:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 04:30:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 04:30:18 INFO - Downloaded 18314917 bytes. 04:30:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:30:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:30:22 INFO - Return code: 0 04:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:22 INFO - https://queue.taskcluster.net/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:30:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 04:30:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 04:30:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 04:30:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 04:30:23 INFO - Downloaded 10840145 bytes. 04:30:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:30:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:30:23 INFO - Return code: 0 04:30:23 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')]} 04:30:23 INFO - https://queue.taskcluster.net/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 04:30:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 04:30:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 04:30:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 04:30:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 04:30:26 INFO - Downloaded 86255466 bytes. 04:30:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 04:30:26 INFO - mkdir: /builds/slave/test/properties 04:30:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:30:26 INFO - Writing to file /builds/slave/test/properties/build_url 04:30:26 INFO - Contents: 04:30:26 INFO - build_url:https://queue.taskcluster.net/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 04:30:27 INFO - Running post-action listener: _resource_record_post_action 04:30:27 INFO - Running post-action listener: set_extra_try_arguments 04:30:27 INFO - ##### 04:30:27 INFO - ##### Running populate-webroot step. 04:30:27 INFO - ##### 04:30:27 INFO - Running pre-action listener: _resource_record_pre_action 04:30:27 INFO - Running main action method: populate_webroot 04:30:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:30:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:30:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:30:27 INFO - '--tppagecycles', 04:30:27 INFO - '7'], 04:30:27 INFO - 'tests': ['tsvgm']}}, 04:30:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:30:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:30:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:30:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:30:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:30:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:30:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:30:27 INFO - 'talos_options': ['--disable-e10s'], 04:30:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:30:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:30:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:30:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:30:27 INFO - 'talos_options': ['--disable-e10s'], 04:30:27 INFO - 'tests': ['damp', 'tps']}, 04:30:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:30:27 INFO - 'tests': ['damp', 'tps']}, 04:30:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:30:27 INFO - 'tests': ['dromaeo_dom']}, 04:30:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:30:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:30:27 INFO - 'tests': ['a11yr', 04:30:27 INFO - 'ts_paint', 04:30:27 INFO - 'tpaint', 04:30:27 INFO - 'sessionrestore', 04:30:27 INFO - 'sessionrestore_no_auto_restore', 04:30:27 INFO - 'tabpaint']}, 04:30:27 INFO - 'other-e10s': {'tests': ['a11yr', 04:30:27 INFO - 'ts_paint', 04:30:27 INFO - 'tpaint', 04:30:27 INFO - 'sessionrestore', 04:30:27 INFO - 'sessionrestore_no_auto_restore', 04:30:27 INFO - 'tabpaint']}, 04:30:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:30:27 INFO - 'ts_paint', 04:30:27 INFO - 'tpaint', 04:30:27 INFO - 'sessionrestore', 04:30:27 INFO - 'sessionrestore_no_auto_restore', 04:30:27 INFO - 'tabpaint']}, 04:30:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:30:27 INFO - 'ts_paint', 04:30:27 INFO - 'tpaint', 04:30:27 INFO - 'sessionrestore', 04:30:27 INFO - 'sessionrestore_no_auto_restore', 04:30:27 INFO - 'tabpaint']}, 04:30:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:30:27 INFO - 'tests': ['a11yr', 04:30:27 INFO - 'ts_paint', 04:30:27 INFO - 'tpaint', 04:30:27 INFO - 'sessionrestore', 04:30:27 INFO - 'sessionrestore_no_auto_restore', 04:30:27 INFO - 'tabpaint']}, 04:30:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:30:27 INFO - 'tests': ['a11yr', 04:30:27 INFO - 'ts_paint', 04:30:27 INFO - 'tpaint', 04:30:27 INFO - 'sessionrestore', 04:30:27 INFO - 'sessionrestore_no_auto_restore', 04:30:27 INFO - 'tabpaint']}, 04:30:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:30:27 INFO - 'tests': ['tsvgx', 04:30:27 INFO - 'tsvgr_opacity', 04:30:27 INFO - 'tart', 04:30:27 INFO - 'tscrollx', 04:30:27 INFO - 'cart']}, 04:30:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:30:27 INFO - 'tsvgr_opacity', 04:30:27 INFO - 'tart', 04:30:27 INFO - 'tscrollx', 04:30:27 INFO - 'cart']}, 04:30:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:30:27 INFO - 'talos_options': ['--disable-e10s'], 04:30:27 INFO - 'tests': ['tp5o']}, 04:30:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:30:27 INFO - 'tests': ['tp5o']}, 04:30:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:30:27 INFO - 'talos_options': ['--disable-e10s', 04:30:27 INFO - '--xperf_path', 04:30:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:30:27 INFO - 'tests': ['tp5n']}, 04:30:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:30:27 INFO - 'talos_options': ['--xperf_path', 04:30:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:30:27 INFO - 'tests': ['tp5n']}}, 04:30:27 INFO - 'talos.zip': {'path': '', 04:30:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:30:27 INFO - Running post-action listener: _resource_record_post_action 04:30:27 INFO - ##### 04:30:27 INFO - ##### Running create-virtualenv step. 04:30:27 INFO - ##### 04:30:27 INFO - Running pre-action listener: _resource_record_pre_action 04:30:27 INFO - Running main action method: create_virtualenv 04:30:27 INFO - Creating virtualenv /builds/slave/test/build/venv 04:30:27 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 04:30:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:30:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:30:27 INFO - Using real prefix '/tools/python27' 04:30:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:30:28 INFO - Installing distribute.............................................................................................................................................................................................done. 04:30:31 INFO - Installing pip.................done. 04:30:31 INFO - Return code: 0 04:30:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:30:31 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')]} 04:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:31 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')]} 04:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:31 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 0x1010fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce5b5b1ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f9de00>, '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 0x100e06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f98b20>, '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.5qZgxSJFPS/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.OBTHWuiNLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 04:30:31 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 04:30:31 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 04:30:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5qZgxSJFPS/Render', 04:30:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:31 INFO - 'HOME': '/Users/cltbld', 04:30:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:31 INFO - 'LOGNAME': 'cltbld', 04:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:31 INFO - 'MOZ_NO_REMOTE': '1', 04:30:31 INFO - 'NO_EM_RESTART': '1', 04:30:31 INFO - 'PAGER': '/bin/cat', 04:30:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:31 INFO - 'PWD': '/builds/slave/test', 04:30:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:31 INFO - 'SHELL': '/bin/bash', 04:30:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OBTHWuiNLS/Listeners', 04:30:31 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 04:30:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:31 INFO - 'USER': 'cltbld', 04:30:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:31 INFO - 'XPC_FLAGS': '0x0', 04:30:31 INFO - 'XPC_SERVICE_NAME': '0', 04:30:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:31 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 04:30:31 INFO - Cleaning up... 04:30:31 INFO - Return code: 0 04:30:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:30:31 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')]} 04:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:31 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')]} 04:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:31 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 0x1010fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce5b5b1ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f9de00>, '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 0x100e06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f98b20>, '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.5qZgxSJFPS/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.OBTHWuiNLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 04:30:31 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 04:30:31 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 04:30:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5qZgxSJFPS/Render', 04:30:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:31 INFO - 'HOME': '/Users/cltbld', 04:30:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:31 INFO - 'LOGNAME': 'cltbld', 04:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:31 INFO - 'MOZ_NO_REMOTE': '1', 04:30:31 INFO - 'NO_EM_RESTART': '1', 04:30:31 INFO - 'PAGER': '/bin/cat', 04:30:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:31 INFO - 'PWD': '/builds/slave/test', 04:30:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:31 INFO - 'SHELL': '/bin/bash', 04:30:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OBTHWuiNLS/Listeners', 04:30:31 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 04:30:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:31 INFO - 'USER': 'cltbld', 04:30:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:31 INFO - 'XPC_FLAGS': '0x0', 04:30:31 INFO - 'XPC_SERVICE_NAME': '0', 04:30:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:32 INFO - Downloading/unpacking psutil>=0.7.1 04:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:30:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:30:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:30:35 INFO - Installing collected packages: psutil 04:30:35 INFO - Running setup.py install for psutil 04:30:35 INFO - building 'psutil._psutil_osx' extension 04:30:35 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 04:30:35 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 04:30:35 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 04:30:35 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 04:30:36 INFO - building 'psutil._psutil_posix' extension 04:30:36 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 04:30:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:30:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:30:36 INFO - ^ 04:30:36 INFO - 1 warning generated. 04:30:36 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 04:30:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:30:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:30:36 INFO - Successfully installed psutil 04:30:36 INFO - Cleaning up... 04:30:36 INFO - Return code: 0 04:30:36 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1010fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce5b5b1ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f9de00>, '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 0x100e06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f98b20>, '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.5qZgxSJFPS/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.OBTHWuiNLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 04:30:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:30:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:30:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5qZgxSJFPS/Render', 04:30:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:36 INFO - 'HOME': '/Users/cltbld', 04:30:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:36 INFO - 'LOGNAME': 'cltbld', 04:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:36 INFO - 'MOZ_NO_REMOTE': '1', 04:30:36 INFO - 'NO_EM_RESTART': '1', 04:30:36 INFO - 'PAGER': '/bin/cat', 04:30:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:36 INFO - 'PWD': '/builds/slave/test', 04:30:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:36 INFO - 'SHELL': '/bin/bash', 04:30:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OBTHWuiNLS/Listeners', 04:30:36 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 04:30:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:36 INFO - 'USER': 'cltbld', 04:30:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:36 INFO - 'XPC_FLAGS': '0x0', 04:30:36 INFO - 'XPC_SERVICE_NAME': '0', 04:30:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:36 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:39 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:30:39 INFO - Installing collected packages: mozsystemmonitor 04:30:39 INFO - Running setup.py install for mozsystemmonitor 04:30:39 INFO - Successfully installed mozsystemmonitor 04:30:39 INFO - Cleaning up... 04:30:39 INFO - Return code: 0 04:30:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:39 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 0x1010fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce5b5b1ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f9de00>, '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 0x100e06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f98b20>, '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.5qZgxSJFPS/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.OBTHWuiNLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 04:30:39 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 04:30:39 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 04:30:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5qZgxSJFPS/Render', 04:30:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:39 INFO - 'HOME': '/Users/cltbld', 04:30:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:39 INFO - 'LOGNAME': 'cltbld', 04:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:39 INFO - 'MOZ_NO_REMOTE': '1', 04:30:39 INFO - 'NO_EM_RESTART': '1', 04:30:39 INFO - 'PAGER': '/bin/cat', 04:30:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:39 INFO - 'PWD': '/builds/slave/test', 04:30:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:39 INFO - 'SHELL': '/bin/bash', 04:30:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OBTHWuiNLS/Listeners', 04:30:39 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 04:30:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:39 INFO - 'USER': 'cltbld', 04:30:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:39 INFO - 'XPC_FLAGS': '0x0', 04:30:39 INFO - 'XPC_SERVICE_NAME': '0', 04:30:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:39 INFO - Downloading/unpacking blobuploader==1.2.4 04:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:42 INFO - Downloading blobuploader-1.2.4.tar.gz 04:30:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:30:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:30:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:30:43 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 04:30:43 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 04:30:43 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 04:30:43 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 04:30:43 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 04:30:43 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 04:30:43 INFO - Downloading docopt-0.6.1.tar.gz 04:30:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:30:43 INFO - Installing collected packages: blobuploader, requests, docopt 04:30:43 INFO - Running setup.py install for blobuploader 04:30:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:30:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:30:43 INFO - Running setup.py install for requests 04:30:44 INFO - Running setup.py install for docopt 04:30:44 INFO - Successfully installed blobuploader requests docopt 04:30:44 INFO - Cleaning up... 04:30:44 INFO - Return code: 0 04:30:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30:44 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')]} 04:30:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:44 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')]} 04:30:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:44 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 0x1010fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce5b5b1ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f9de00>, '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 0x100e06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f98b20>, '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.5qZgxSJFPS/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.OBTHWuiNLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 04:30:44 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 04:30:44 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 04:30:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5qZgxSJFPS/Render', 04:30:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:44 INFO - 'HOME': '/Users/cltbld', 04:30:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:44 INFO - 'LOGNAME': 'cltbld', 04:30:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:44 INFO - 'MOZ_NO_REMOTE': '1', 04:30:44 INFO - 'NO_EM_RESTART': '1', 04:30:44 INFO - 'PAGER': '/bin/cat', 04:30:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:44 INFO - 'PWD': '/builds/slave/test', 04:30:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:44 INFO - 'SHELL': '/bin/bash', 04:30:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OBTHWuiNLS/Listeners', 04:30:44 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 04:30:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:44 INFO - 'USER': 'cltbld', 04:30:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:44 INFO - 'XPC_FLAGS': '0x0', 04:30:44 INFO - 'XPC_SERVICE_NAME': '0', 04:30:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:30:44 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-XTMxAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:30:44 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-pJktBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:30:44 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-PLxpAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:30:44 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-cwuz0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:30:45 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-xNjQHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:30:45 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-WKvM5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:30:45 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-SSo3oP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:30:45 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-mqTh2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:30:45 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-EJ9qT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:30:45 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-9R_iyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:30:45 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-7cF77m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:30:45 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-xw_YMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:30:45 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-QOVpe1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:30:45 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-zHHs27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:46 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-qG0nV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:30:46 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-B33HVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:30:46 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-JySPXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:30:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:30:46 INFO - Running setup.py install for manifestparser 04:30:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:30:46 INFO - Running setup.py install for mozcrash 04:30:46 INFO - Running setup.py install for mozdebug 04:30:46 INFO - Running setup.py install for mozdevice 04:30:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:30:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:30:46 INFO - Running setup.py install for mozfile 04:30:47 INFO - Running setup.py install for mozhttpd 04:30:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:30:47 INFO - Running setup.py install for mozinfo 04:30:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:30:47 INFO - Running setup.py install for mozInstall 04:30:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:30:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:30:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:30:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:30:47 INFO - Running setup.py install for mozleak 04:30:47 INFO - Running setup.py install for mozlog 04:30:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:30:47 INFO - Running setup.py install for moznetwork 04:30:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:30:48 INFO - Running setup.py install for mozprocess 04:30:48 INFO - Running setup.py install for mozprofile 04:30:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:30:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:30:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:30:48 INFO - Running setup.py install for mozrunner 04:30:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:30:48 INFO - Running setup.py install for mozscreenshot 04:30:48 INFO - Running setup.py install for moztest 04:30:48 INFO - Running setup.py install for mozversion 04:30:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:30:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:30:48 INFO - Cleaning up... 04:30:48 INFO - Return code: 0 04:30:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:48 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 0x1010fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce5b5b1ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f9de00>, '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 0x100e06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f98b20>, '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.5qZgxSJFPS/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.OBTHWuiNLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 04:30:48 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 04:30:48 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 04:30:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5qZgxSJFPS/Render', 04:30:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:48 INFO - 'HOME': '/Users/cltbld', 04:30:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:48 INFO - 'LOGNAME': 'cltbld', 04:30:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:48 INFO - 'MOZ_NO_REMOTE': '1', 04:30:48 INFO - 'NO_EM_RESTART': '1', 04:30:48 INFO - 'PAGER': '/bin/cat', 04:30:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:48 INFO - 'PWD': '/builds/slave/test', 04:30:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:48 INFO - 'SHELL': '/bin/bash', 04:30:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OBTHWuiNLS/Listeners', 04:30:48 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 04:30:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:48 INFO - 'USER': 'cltbld', 04:30:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:48 INFO - 'XPC_FLAGS': '0x0', 04:30:48 INFO - 'XPC_SERVICE_NAME': '0', 04:30:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:30:49 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-B2Htyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:30:49 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)) 04:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:30:49 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-oxrgBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:30:49 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)) 04:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:30:49 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-yKSRry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:30:49 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)) 04:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:30:49 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-VqpyuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:30:49 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)) 04:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:30:49 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-pdpTKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:30:49 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)) 04:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:30:49 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Kp3Q3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:30:49 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)) 04:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:30:49 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-_FpeWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:30:49 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)) 04:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:30:49 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-UrvVpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:30:50 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)) 04:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:30:50 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-eOlpXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:30:50 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)) 04:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:30:50 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-eXzmhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:30:50 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)) 04:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:30:50 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-JrNY9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:30:50 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)) 04:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:30:50 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-sY81rs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:30:50 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)) 04:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:30:50 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-bUPw1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:30:50 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)) 04:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:30:50 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-2pJPla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:30:50 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)) 04:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:50 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-jyiX6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:50 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)) 04:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:30:50 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-56mBUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:30:50 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)) 04:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:30:50 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-NpixAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:30:51 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)) 04:30:51 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)) 04:30:51 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)) 04:30:51 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)) 04:30:51 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)) 04:30:51 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)) 04:30:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:53 INFO - Downloading blessings-1.6.tar.gz 04:30:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:30:53 INFO - Installing collected packages: blessings 04:30:53 INFO - Running setup.py install for blessings 04:30:53 INFO - Successfully installed blessings 04:30:53 INFO - Cleaning up... 04:30:53 INFO - Return code: 0 04:30:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:30:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:30:54 INFO - Reading from file tmpfile_stdout 04:30:54 INFO - Current package versions: 04:30:54 INFO - blessings == 1.6 04:30:54 INFO - blobuploader == 1.2.4 04:30:54 INFO - docopt == 0.6.1 04:30:54 INFO - manifestparser == 1.1 04:30:54 INFO - mozInstall == 1.12 04:30:54 INFO - mozcrash == 0.17 04:30:54 INFO - mozdebug == 0.1 04:30:54 INFO - mozdevice == 0.48 04:30:54 INFO - mozfile == 1.2 04:30:54 INFO - mozhttpd == 0.7 04:30:54 INFO - mozinfo == 0.9 04:30:54 INFO - mozleak == 0.1 04:30:54 INFO - mozlog == 3.1 04:30:54 INFO - moznetwork == 0.27 04:30:54 INFO - mozprocess == 0.22 04:30:54 INFO - mozprofile == 0.28 04:30:54 INFO - mozrunner == 6.11 04:30:54 INFO - mozscreenshot == 0.1 04:30:54 INFO - mozsystemmonitor == 0.0 04:30:54 INFO - moztest == 0.7 04:30:54 INFO - mozversion == 1.4 04:30:54 INFO - psutil == 3.1.1 04:30:54 INFO - requests == 1.2.3 04:30:54 INFO - wsgiref == 0.1.2 04:30:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30: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')]} 04:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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')]} 04:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:54 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 0x1010fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce5b5b1ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f9de00>, '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 0x100e06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f98b20>, '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.5qZgxSJFPS/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.OBTHWuiNLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 04:30:54 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 04:30:54 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 04:30:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5qZgxSJFPS/Render', 04:30:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:54 INFO - 'HOME': '/Users/cltbld', 04:30:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:54 INFO - 'LOGNAME': 'cltbld', 04:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:54 INFO - 'MOZ_NO_REMOTE': '1', 04:30:54 INFO - 'NO_EM_RESTART': '1', 04:30:54 INFO - 'PAGER': '/bin/cat', 04:30:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:54 INFO - 'PWD': '/builds/slave/test', 04:30:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:54 INFO - 'SHELL': '/bin/bash', 04:30:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OBTHWuiNLS/Listeners', 04:30:54 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 04:30:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:54 INFO - 'USER': 'cltbld', 04:30:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:54 INFO - 'XPC_FLAGS': '0x0', 04:30:54 INFO - 'XPC_SERVICE_NAME': '0', 04:30:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:54 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)) 04:30:54 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)) 04:30:54 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)) 04:30:54 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)) 04:30:54 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)) 04:30:54 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)) 04:30:54 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)) 04:30:54 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)) 04:30:54 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)) 04:30:54 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)) 04:30:54 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)) 04:30:54 INFO - Cleaning up... 04:30:54 INFO - Return code: 0 04:30:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:30: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')]} 04:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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')]} 04:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1010fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce5b5b1ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f9de00>, '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 0x100e06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f98b20>, '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.5qZgxSJFPS/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.OBTHWuiNLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 04:30:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:30:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:30:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5qZgxSJFPS/Render', 04:30:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:54 INFO - 'HOME': '/Users/cltbld', 04:30:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:54 INFO - 'LOGNAME': 'cltbld', 04:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:54 INFO - 'MOZ_NO_REMOTE': '1', 04:30:54 INFO - 'NO_EM_RESTART': '1', 04:30:54 INFO - 'PAGER': '/bin/cat', 04:30:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:54 INFO - 'PWD': '/builds/slave/test', 04:30:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:54 INFO - 'SHELL': '/bin/bash', 04:30:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OBTHWuiNLS/Listeners', 04:30:54 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 04:30:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:54 INFO - 'USER': 'cltbld', 04:30:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:54 INFO - 'XPC_FLAGS': '0x0', 04:30:54 INFO - 'XPC_SERVICE_NAME': '0', 04:30:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:54 INFO - Downloading/unpacking jsonschema 04:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:30:57 INFO - Downloading/unpacking functools32 (from jsonschema) 04:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:57 INFO - Downloading functools32-3.2.3-2.tar.gz 04:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:30:57 INFO - warning: no files found matching '*.txt' 04:30:57 INFO - no previously-included directories found matching 'build' 04:30:57 INFO - no previously-included directories found matching 'dist' 04:30:57 INFO - no previously-included directories found matching '.git*' 04:30:57 INFO - Installing collected packages: jsonschema, functools32 04:30:57 INFO - Running setup.py install for functools32 04:30:57 INFO - warning: no files found matching '*.txt' 04:30:57 INFO - no previously-included directories found matching 'build' 04:30:57 INFO - no previously-included directories found matching 'dist' 04:30:57 INFO - no previously-included directories found matching '.git*' 04:30:57 INFO - Successfully installed jsonschema functools32 04:30:57 INFO - Cleaning up... 04:30:58 INFO - Return code: 0 04:30:58 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:58 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 0x1010fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce5b5b1ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f9de00>, '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 0x100e06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f98b20>, '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.5qZgxSJFPS/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.OBTHWuiNLS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 04:30:58 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 04:30:58 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 04:30:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5qZgxSJFPS/Render', 04:30:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:58 INFO - 'HOME': '/Users/cltbld', 04:30:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:58 INFO - 'LOGNAME': 'cltbld', 04:30:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:58 INFO - 'MOZ_NO_REMOTE': '1', 04:30:58 INFO - 'NO_EM_RESTART': '1', 04:30:58 INFO - 'PAGER': '/bin/cat', 04:30:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:58 INFO - 'PWD': '/builds/slave/test', 04:30:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:58 INFO - 'SHELL': '/bin/bash', 04:30:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OBTHWuiNLS/Listeners', 04:30:58 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 04:30:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:58 INFO - 'USER': 'cltbld', 04:30:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:58 INFO - 'XPC_FLAGS': '0x0', 04:30:58 INFO - 'XPC_SERVICE_NAME': '0', 04:30:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:58 INFO - Downloading/unpacking flake8 04:30:58 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 04:30:58 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 04:30:58 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 04:30:58 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 04:30:58 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 04:30:58 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 04:31:00 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:31:00 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:31: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 04:31: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 04:31: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 04:31: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 04:31: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 04:31: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 04:31:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:31:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:31:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:31:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:31:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:31:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:31:02 INFO - no previously-included directories found matching 'docs/_build' 04:31:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:31:02 INFO - Running setup.py install for pep8 04:31:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:31:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:31:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:31:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:31:02 INFO - no previously-included directories found matching 'docs/_build' 04:31:02 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:31:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:31:02 INFO - Cleaning up... 04:31:02 INFO - Return code: 0 04:31:02 INFO - Running post-action listener: _resource_record_post_action 04:31:02 INFO - Running post-action listener: _start_resource_monitoring 04:31:02 INFO - Starting resource monitoring. 04:31:02 INFO - ##### 04:31:02 INFO - ##### Running install step. 04:31:02 INFO - ##### 04:31:02 INFO - Running pre-action listener: _resource_record_pre_action 04:31:02 INFO - Running main action method: install 04:31:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:31:02 INFO - Reading from file tmpfile_stdout 04:31:02 INFO - Detecting whether we're running mozinstall >=1.0... 04:31:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:31:02 INFO - Reading from file tmpfile_stdout 04:31:02 INFO - Output received: 04:31:02 INFO - Usage: mozinstall [options] installer 04:31:02 INFO - Options: 04:31:02 INFO - -h, --help show this help message and exit 04:31:02 INFO - -d DEST, --destination=DEST 04:31:02 INFO - Directory to install application into. [default: 04:31:02 INFO - "/builds/slave/test"] 04:31:02 INFO - --app=APP Application being installed. [default: firefox] 04:31:02 INFO - mkdir: /builds/slave/test/build/application 04:31:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 04:31:24 INFO - Reading from file tmpfile_stdout 04:31:24 INFO - Output received: 04:31:24 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 04:31:24 INFO - Running post-action listener: _resource_record_post_action 04:31:24 INFO - ##### 04:31:24 INFO - ##### Running run-tests step. 04:31:24 INFO - ##### 04:31:24 INFO - Running pre-action listener: _resource_record_pre_action 04:31:24 INFO - Running main action method: run_tests 04:31:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:31:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:31:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:31:24 INFO - 2016-07-25 04:31:24.654 screenresolution[1640:7502] starting screenresolution argv=screenresolution get 04:31:24 INFO - 2016-07-25 04:31:24.657 screenresolution[1640:7502] Display 0: 1600x1200x32@60 04:31:24 INFO - 2016-07-25 04:31:24.663 screenresolution[1641:7505] starting screenresolution argv=screenresolution list 04:31:24 INFO - Available Modes on Display 0 04:31:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:31:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:31:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:31:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:31:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:31:24 INFO - Intel Iris: 04:31:24 INFO - Chipset Model: Intel Iris 04:31:24 INFO - Type: GPU 04:31:24 INFO - Bus: Built-In 04:31:24 INFO - VRAM (Dynamic, Max): 1536 MB 04:31:24 INFO - Vendor: Intel (0x8086) 04:31:24 INFO - Device ID: 0x0a2e 04:31:24 INFO - Revision ID: 0x0009 04:31:24 INFO - Displays: 04:31:24 INFO - Display: 04:31:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:31:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:31:24 INFO - Display Serial Number: 1600x1200 60 04:31:24 INFO - Main Display: Yes 04:31:24 INFO - Mirror: Off 04:31:24 INFO - Online: Yes 04:31:24 INFO - Rotation: Supported 04:31:24 INFO - Return code: 0 04:31:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:31:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:31:24 INFO - Python 2.7.3 04:31:24 INFO - Return code: 0 04:31:24 INFO - grabbing minidump binary from tooltool 04:31:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:31:24 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 0x100f9de00>, '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 0x100e06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f98b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:31:24 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 04:31:24 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 04:31:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:31:24 INFO - Return code: 0 04:31:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:31:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:31:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:31:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:31:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:31:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:31:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:31:26 INFO - Return code: 0 04:31:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0147 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:31:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5qZgxSJFPS/Render', 04:31:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:26 INFO - 'HOME': '/Users/cltbld', 04:31:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:26 INFO - 'LOGNAME': 'cltbld', 04:31:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:31:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:26 INFO - 'MOZ_NO_REMOTE': '1', 04:31:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:31:26 INFO - 'NO_EM_RESTART': '1', 04:31:26 INFO - 'PAGER': '/bin/cat', 04:31:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:26 INFO - 'PWD': '/builds/slave/test', 04:31:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:31:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:26 INFO - 'SHELL': '/bin/bash', 04:31:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OBTHWuiNLS/Listeners', 04:31:26 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 04:31:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:26 INFO - 'USER': 'cltbld', 04:31:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:26 INFO - 'XPC_FLAGS': '0x0', 04:31:26 INFO - 'XPC_SERVICE_NAME': '0', 04:31:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:31:26 INFO - mozversion application_buildid: 20160725022027 04:31:26 INFO - mozversion application_changeset: f3d7abb885c267a7657e3b8ea06c18f76eb69641 04:31:26 INFO - mozversion application_display_name: Firefox 04:31:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:31:26 INFO - mozversion application_name: Firefox 04:31:26 INFO - mozversion application_remotingname: firefox 04:31:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:31:26 INFO - mozversion application_vendor: Mozilla 04:31:26 INFO - mozversion application_version: 48.0 04:31:26 INFO - mozversion platform_buildid: 20160725022027 04:31:26 INFO - mozversion platform_changeset: f3d7abb885c267a7657e3b8ea06c18f76eb69641 04:31:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:31:26 INFO - mozversion platform_version: 48.0 04:31:26 INFO - using testdate: 1469446286 04:31:26 INFO - actual date: 1469446286 04:31:26 INFO - starting webserver on 'localhost:49277' 04:31:26 INFO - SUITE-START | Running 2 tests 04:31:26 INFO - TEST-START | dromaeo_css 04:31:26 INFO - operating with platform_type : mac_ 04:31:26 INFO - Initialising browser for dromaeo_css test... 04:31:26 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmp1Sow9z/profile http://localhost:49277/getInfo.html) 04:31:29 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 04:31:29 INFO - PROCESS | 1659 | colorDepth:24 04:31:29 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 04:31:29 INFO - PROCESS | 1659 | __metrics 04:31:29 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 04:31:29 INFO - TEST-INFO | 1659: exit 0 04:31:29 INFO - Browser initialized. 04:31:29 INFO - Running cycle 1/1 for dromaeo_css test... 04:31:29 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmp1Sow9z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:31:41 INFO - PROCESS | 1663 | RSS: Main: 171024384 04:31:41 INFO - PROCESS | 1663 | 04:32:31 INFO - PROCESS | 1663 | 1469446351548 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] 04:34:42 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 04:34:43 INFO - PROCESS | 1663 | RSS: Main: 185110528 04:34:43 INFO - PROCESS | 1663 | 04:37:45 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 04:37:46 INFO - PROCESS | 1663 | RSS: Main: 195764224 04:37:46 INFO - PROCESS | 1663 | 04:40:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 04:40:48 INFO - PROCESS | 1663 | RSS: Main: 197718016 04:40:48 INFO - PROCESS | 1663 | 04:43:50 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 04:43:50 INFO - PROCESS | 1663 | RSS: Main: 201523200 04:43:50 INFO - PROCESS | 1663 | 04:46:53 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 04:46:53 INFO - PROCESS | 1663 | RSS: Main: 200548352 04:46:53 INFO - PROCESS | 1663 | 04:49:56 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 04:49:56 INFO - PROCESS | 1663 | RSS: Main: 206344192 04:49:56 INFO - PROCESS | 1663 | 04:49:56 INFO - PROCESS | 1663 | __start_tp_report 04:49:56 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 04:49:56 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 04:49:56 INFO - PROCESS | 1663 | |i|pagename|runs| 04:49:56 INFO - PROCESS | 1663 | |0;dojo.html;3150.5589217509546;3713.29447404993;3719.107414220588;3743.66307032367;3757.210985693003;6063.787767428141;6068.423499767526;6071.180390647261;6076.21009268795;6094.725737341819;5949.613275137124;6074.392560743923;6094.908576371358;6097.146399504073;6124.387561243884;5370.194470829368;5435.42927992561;5470.070088085035;5520.503154716075;5567.331920169585;5336.012837625051;5454.836354909339;5455.099908515091;5518.061929471979;5628.380878103405;4230.894227644303;4517.751523666214;5214.843554693365;5365.3367456088545;5560.443955604427;3879.9030024249337;5485.204645326406;5513.58648101394;5541;5556.666600004012;5270.130428479296;5343.332083489564;5364.088105022156;5392.757325920343;5442.727863606804;4906.386701662293;5917.763289468416;5932.021216499273;5961.642301461926;6085.4827339676185;6223.564350495467;6422.068800023971;6431.324710905381;6431.774887878902;6436.098946147543;6120.693965301717;6513.674316284167;6515.902261466082;6522.608643481359;6528.21661400635;6027.638341699469;6050;6078.848028799272;6145.477634401082;6177.598456100339;6467.353264673589;6568.1789776277965;6637.800865974028;6648.03603477493;6663.233728121231;6265.122882796412;6276.372362763687;6331.2085989251345;6332.461740751995;6346;6454.612723236621;6464.450521705614;6465.935340646627;6466.547341686037;6481.222253329536;6228.4705800007505;6286.088517165077;6312.337204593543;6330.240371155491;6369.649669268142;6350.047492876106;6367.363263673596;6375.936240637534;6377.840553986188;6398.680065996681;5098.490150984946;5109.642325037286;5154.123798954187;5162.148245539519;5167.069927413027;4574.6340292776495;4588.082383523242;4601.67788254819;4651.720896746206;4701.905961880743;5385.569154467633;6087.786927457606;6203.7828675996125;6248.28144763349;6269.8746025079245;6469.223693156757;6496;6505.154329937078;6512.869742605121;6533.987231979051;6149.631022138686;6217.440430361289;6239.594426362272;6258.468030217391;6265.498760099181;5671.829845104653;5683.2895888014;5684.77260909559;5693.829185124453;5695.430456954271;6743.123393958852;6765.627890466071;6779.559328643622;6799.184097908084;6808.38724514796;6127.433875357673;6430.549861509649;6487.124238227779;6526.608403495806;6532.706028228646;6735.831604209934;6752.324767523405;6771.52599318043;6784.660766961533;6791.422729067887;6235.469985051322;6344.587601805868;6402.807915762534;6410.358964103552;6418;6323.525735569759;6436.227652681709;6464.644444555406;6490.383413575702;6511.902321465087;6300.558960872878;6304.117423560797;6329.145565348619;6339.778107766206;6356.745730170742;6188.566800323934;6300.306966233802;6308.968455157695;6312.179416675803;6340.048992651139;4854.854354369375;6115.205023347115;6174;6222.875542489034;6245.781397651061;6213.254409470893;6255.906161407492;6261.3112557617715;6261.467775238973;6271.874562508633;6129.083728908335;6156.415140561639;6167.907481387693;6190.473809726223;6242.687865606792 04:49:56 INFO - PROCESS | 1663 | |1;ext.html;510.2652180859564;531.7713383245203;539.5063516882053;565.6860442454438;625.5714835337794;40855.59144408555;41929;41935;41956.160876782444;41981.79009104958;43281.567184328145;43649.563504364945;44505.554944450625;44570;44587.33119003209;9607.615695372195;9754.561044753003;9853.310268281222;9866.16137628299;9866.210703143732;9756.756081097958;9771.169450596672;9844.70465886021;9854.753631159243;9856.753581160492;9547.045295470467;9766;9785.217182625409;9808.607655693764;9898.50507474627;9764.632951386811;9802.852957205647;9868.161206297402;9877.308588398779;9882.901170988342;9647.90352096477;9659.178969787576;9672.08115229052;9775.071368220008;9788.706338809847;39783.20433591341;39910.60089399098;40248.597514025074;40692.79653601743;40778.38832417516;2955.7783166262616;3190.6968837960358;3241.5623890774914;3328.766986310959;3403.9659603403898;28044.579331310048;29492.70507294921;29740.107796766133;30381.69618303833;30462.69537304643;2576.536223479773;2586.741325867417;2613.9346516337055;2671.919842404731;2731.808773385844;386720;387586;390282.04858975805;391608;392974.99999999715;37999.05002374963;38691.613083869364;38838.611613884066;64537.35462645313;65105.023424648665;37337.81331093327;37430.251394972496;37618.43572346417;37738.811305943294;37812.62187378146;15437.84562154364;15571;15887.841121588868;16016;16046.27791749374;13063.869361306266;13107.672308192181;13109.213447193199;13188.934055329662;13246.867531324564;8416.326693864476;8703.695370662123;8724.43291186071;8748.387612867038;8786.42888212277;3650.1969566695284;3691.8523259069875;3708.6593415339134;3709;3717.9628203717616;995.2535598301274;1023.9692809215735;1058.0847566854616;1068.9874423779872;1106.4080716816625;28199.15402537927;30798.384032319227;31323.84338078295;31361.372772544877;31663.841680791447;1079.233744041739;1101.372217835826;1113.5768408004906;1129.079799963044;1163.8410748062392;12873.806892896613;15189.848101519065;15194.620134496727;15364.923175384052;15577.143257120968;622129;624977;629488;640125;640313;58019.4198058014;59627;60317.69841150766;60329.69835150796;60375.69812150999;1287.3756228229302;1295.8185853980451;1538.9384424623113;1586.6112802363264;1614.31391658547;27146.864265678545;29789.957351492594;30285.69714302917;30890;30971.380572388876;42688.573114269704;48195.277070843265;52172.95654086973;54007.72996135151;54554;496444;570279;572577;575671;590167;27298.04456844001;27662.5850612245;27749.583756243268;27752.444951101268;27998.3000424991;45468.86327841698;46025.76987115043;46105;46837.76581117073;47428.05143897034;30131.698683013765;37754.43368349532;38011;38415.807920960215;38687.41968870413;37604.97915378372;37789.81105094457;38009.80995095007;38305.2338953225;38383.61616383912;37764.81117594394;38076.23847523089;38287.23425531418;38723.612763872;39038.6096139035;32527;33819.985400438025;34035.319293614484;34173.487397689554;34293.314133717686;25550.74449255558;25755.227343179733;25856.741432585433;26296;26324.473510530064 04:49:56 INFO - PROCESS | 1663 | |2;jquery.html;354.26490033181153;413.95446500884896;415.6404709925913;420.9705320627556;427.36749610576345;20740.792592074093;21414.14343426265;21435;21482.785172148273;22721.54556908865;21046.57906841862;21521.923903804785;21824.454388640293;22114.557708845812;22183;5911.113333000063;5938.13896984938;6232.968835155841;6238.064290356438;6249.875002499947;6022.6687532185715;6150.077488376734;6163.414475624831;7038.15542134942;7038.6128762918015;6909.239983601825;6940.30596940307;6987.161540615133;7006.859862802767;7008.229094799568;6657.766978155765;6859.108315918948;6865.41643960264;6869.347411995853;6956.304369563054;6057.939420605826;6239.188905442309;6250.531210159256;6262.655553944577;6271.435570798604;22510;24355.878220608785;26274.080407185847;26970.460590788076;27314.5902811458;2210.801027907496;2220.222921977311;2295.931122066341;2477.7893879020303;2535;10198.796024079551;10250.333728307636;10414.323069000491;10750.301230420077;10862.3482591044;1811.3479147506887;1849.805770394116;1895.981040189608;1918.8464922806143;1992.1234656750983;119350.40324798407;122688.38655806753;123305;124954.37522812419;125395.37302313521;9964.501774911225;11985.880141198651;12151.270923744603;12224.388780560936;12282.631521054383;15780.368785248691;15798.9210053949;15818.60453488649;15884.046957182605;15884.76172857408;7403.444741644398;8714.477131372138;9259.629614815467;9274;9331.300152488591;9096.1813436791;9215.585298661443;9234.86147707785;9248.907510924939;9302.813943721088;4813.663043586986;4849.4908034656555;4862.100511405401;4911.435184953776;4934.136526107916;2067.9483012924497;2119.6184686756533;2154.795294447025;2167.198136689435;2178.553396553703;856.9160012584086;930.3766476460787;945.0974319524975;1010.2170817616407;1081.507913899174;16749;18335.816641833677;19014.573906957034;21222.67565560983;21772.346829594822;904.2856143646445;929.2565947242314;1021.1269364693229;1052.5105825791045;1083.6075642798965;11132.053775429018;11223.270487418291;11299.44063157061;11352.177310577188;11385.08919286455;656274;671183;673061;682197;687283;123926;129584;134058;137822.31088844492;143223;1032.9586816527255;1037.6679462572056;1049.0296475759892;1049.4123290957089;1050.2018465966073;18834.811651883854;19331.613367732287;19381.90309048446;19389.321373751853;19612.313569025016;52891.206631901325;54024.18963715469;55118.44881551133;56731;56991.14513282221;77531.61234193793;79275.20724792678;79745.60127199326;80241;81960.18039819764;8371.748847534584;8452.492850428995;8553.74338769813;8680.522571258642;8996.145366190201;12527.37363131858;12910.548130815376;13027.47890084386;13176.934115329363;13214.537491187762;10666.360018398611;10984.560617575207;11256.21206515536;11465.140114491613;11558.942205289257;10893.564257429614;11065.114790816877;11258.043066339125;11315.547378104784;11364.602238921598;11420.543178273108;11563.074954003827;11632.592859249886;11749.353785541878;11768.823467648162;8059.556724380215;8398.118197589165;8439.324854011542;8662.91337086621;8834.64661413562;8349.206825351583;10295.845562316421;11319.151063670093;11504.827427588425;11529 04:49:56 INFO - PROCESS | 1663 | |3;mootools.html;1379.979300310495;1597.5686564627547;1838.3106335124328;2013.345662659636;2066.85532012759;8509.064002959669;9189.127032931861;9341.439513629179;9540.045995400465;9555.426674399532;9308.441493510378;9312.81374372512;9439.480828554444;9451.007644197363;9470.38442501237;2514.006967247936;2544.847309161447;2554.441790507789;2560.4623029163804;2568.563344231485;2528.2162529615784;2578.2007577650893;2581.5611346071214;2609.712931577525;2612.830166039211;2558.7185409604926;2576.5828794163135;2590.4042070323835;2620.082970960158;2626.1333759859294;2138.8823614701187;2176.889786209029;2205.162038425396;2321.4312493439047;2625.0812215724536;2568;2569.1521797806677;2603.0498867913266;2616.2936007277926;2640.049582150424;9440.150386465182;9525.714228573153;9574;9579.377340472916;9611.231101511863;5698.601097923147;5759.222504961818;5783.306003279633;5917.467427931464;5917.615355001911;3704.8888533344043;3779.45197946299;3798.4302354646748;3807.5240594925635;3815.313243616148;1072.4423299884018;1073.414988831089;1078.266778590558;1080.308602494404;1087.6302057300556;12390.56633017849;12400.56598019074;12414.131010829336;12414.689632759253;12750.661180575999;5095.97452012745;5120.92318615221;5197.506236907488;5223.9477605223465;5259.237410575445;10573.207009474167;10603.204759643058;10837.295575787548;10868.728281793019;10879.945600272107;5685.60200785941;5733.827985160452;5749.252597162342;5854.912176317359;5900.43945825146;4671.135839869635;5109.770060347293;5224.843254702365;5228.320318358665;5260.421353651095;3511.5610548681416;4368.672349573794;4427;4476.194285028726;4483.349914262414;1209.4013463335705;1423.195894319706;1463.0855715178013;1464.3703207620613;1468.2014532197973;856.3577317012241;973.6786860336072;981.3326937682444;986.5807032011367;1007.652359935821;6097.69511524422;6184.783532576428;6262.538767504526;6304.180456540712;6308.179936608211;485.980560777572;486.20020066989605;492.93838270216224;493.13454886673657;500.82971789591716;3633.8546458141377;3668.825975687754;4086.40747091676;4118.155778065489;4173.979130104391;9671.839379274392;11159.38623375722;11569.305841649528;11623.953844154066;11674.591389301504;9327.580258888367;10937.289076210021;10974.780504389868;10984.450777461096;11109.555617775199;8414.410991230556;8629.78425539354;8794.988576313677;8848.955755221312;8957.014728379872;4931.334269873664;5490.450954904637;6192.659403732658;6202.906956395567;6266.843328916813;10082.848757268794;10212.183025358103;10241.74395640115;10263.846042309222;10373;8830.514321712368;9991.050850169222;10088.949555252177;10397.584096636352;10426.895731042592;7273.454490913097;7720.420968427503;7922.920770792219;7994.0407151142235;8010.759677209693;7305.488615796843;8754.60604272822;8792.29661627081;8809.559522023745;8835.734927952171;7126.928730712834;7419.4790068034845;7802.102758182881;7855.60721963888;7925.643346049557;7630.694772209272;7701.92298077012;7723.497972631761;7783.532988020737;7801.648925798466;7374.889376659247;7442.479026468095;7532.661030253542;7568.735094271894;7584.54492730438;5085;5122.231665250242;5194.558462530727;5270.89458210826;5275.788968441219;5270.552003079782;5304.124819404909;5354.11657076578;5468.9179662304305;5483.314585676791 04:49:56 INFO - PROCESS | 1663 | |4;prototype.html;334.66700632870294;340.9863605455782;344.21347221598654;347.32098746949714;350.32212668486466;6339.334369891153;6930.341617546338;6959.025736396902;6998.125234345707;7011.509194356391;5507.972460137704;6904.75833345833;6925.168979722441;6933.549319294242;6936.895946560806;4939.654224204307;5044.117279476095;5125.641205115643;5134.7175905325375;5163.380394352683;4954.439351604233;5149.485051494858;5169.940162266745;5199.324087868572;5217.426083130853;4566.703164294327;5178.715170665612;5182.740862956837;5197.558207552363;5202.271681964528;5174.301469301652;5187.092258854705;5190.221466779994;5204.687718736888;5222.0861349264105;4467.01725620363;4467.061916997434;4470.41884555009;4474.239379305526;4474.418325617681;5368.570514358881;6509.3490650935;6704.765333213363;6767.255601883789;6805.706915686053;5964.552658550626;5970.6716130612795;5996.460318571306;6000.5799594028;6022.603499445386;6011.57918945674;6839.692213850339;7184;7489.812754681122;7506.249375062504;4779.952200477986;4782.1631214537665;4790.42514898211;4796.232602783538;4802.855914322576;7606.467547271694;7896;7937.325327347183;7974.880376794353;7981.241782030698;4141.337386018223;4851.272309153655;4868.95131048692;4882.633802464758;4892;4933.728644924492;4942.777575009133;4943.65394422387;4950.554450099508;4950.603951683928;4968;5823.5923485355615;5945.078512830518;5961.076033214858;5963.373845746221;7478.962605186939;7482.5884576347735;7485.401167906554;7487;7494.100707914976;1662.401535447238;1696.6437048220012;1727.853132483753;1733.4886208568503;1737.1140718233792;866.332923648787;887.924526415249;1002.8467262647868;1080.4171888183848;1104.1994553948484;7247.311505406978;7257.346838784535;7262.20115787263;7274.672639731225;7283.2716728327805;6260.937390626126;7440.3675687567165;7692.576908269987;7746.9612651937505;7793.454458187985;7259.346658800733;7269.8546029079125;7272.890906636405;7322.231165727522;7327.5969821659255;3321.9501707474406;4096.3855421686385;4102.958970410317;4144.046869220036;4149.2738770715005;8207;8223.629936652866;8224.958875205586;8335.166483351617;8374.916250837536;7837.373010159174;8077.8788318175275;8159.71440999574;8160;8163.0612479564425;8300.294474969574;8301.750947471464;8318.500889946623;8320.91828062348;8331;7836.333911617577;7904.2886140245355;7921.841563168819;7927;7936.960315198618;6189.381061893775;7401.740939067106;7745;7769.184235655175;7773.183815699496;8283.337333013224;8306.70926517569;8314.251717345225;8331.458455200393;8350.081491036102;4125.370478660972;4873.537013983666;4879.39007624047;4918.336024636628;4924.926126108183;7860.0567931848545;7868.213178682315;7871.48835325702;7884.21157884207;7901.012373453318;7276.927230727625;7297.525660832029;7571.432142589218;7580.583067931317;7588.886166707614;6121.632702037892;6534.705938232886;7559.357454616419;7603.20166382544;7615.885761713468;5989.401059893976;7439.070116235471;7440.776776696708;7451.993980812737;7458.328750412489;7545.886811697719;7570.356519695888;7600.619969001417;7602.3537999268465;7612;7221.883182077665;7357.227491113356;7377.5204611700065;7453.515521491086;7465.813354666177 04:49:56 INFO - PROCESS | 1663 | |5;yui.html;708.8410448916021;721.8628460592487;737.7749786315172;787.1223585701943;802.3861745764488;15606.375744970215;15613.375464981402;15638;16005.519834404971;16029.358825646974;14388.280585970711;14751.336189871454;14771.852281477182;14785.334659940301;14786.334614942327;751.2825251884437;910.1308250620641;913.488446469979;917.9173874351324;927.2628260532858;878.5387671472496;881.066069965835;902.2917010147036;914.1270087066869;954.2080073538981;941.2940294778916;941.948192849393;941.9623215071389;943.9244860411151;1013.4274135113637;933.5705575435308;934.7476181430976;971.0677749360623;973.5181085362719;1010.0707349238718;724.199759266014;741.2994719989612;747.8840779679158;751.6993202718902;753.6081237756391;12882.033847461384;15535.067895926168;15701.214939252992;15735.763963540556;15823.920880395639;13153.73692526144;13334.466621335136;13410.86589134106;13417.597472075853;13483.797743033765;10116.038976297314;10196.439195844225;10223.795524089552;10353.482325883675;10490.31812932157;3573.1245844767936;3586.910327241814;3591.6049234584175;3643.4352675335367;3682.723795715332;59228.99999999957;82669;82724;83004.58497707533;83513;9568.665096721581;9676.70969870905;9953.153981911619;10146;10421.791564168674;23059.6541051881;23509.059637614646;23607.81960901948;23938.52122957531;24095;5959.702014899324;6052.848678783065;6115.205023346936;6131.172291740647;6230.937690623126;4126.09225970286;4165.522886418886;4228.767417792051;4251.404803327537;4266.722663026893;3645.179834537208;3691.9261614768093;3760.623937606272;3764.811759412073;3764.981175094107;652.670401447266;670.9060731497594;672.4855485553555;676.2088356622763;676.5940435738518;533.1868899927641;576.5416493887286;585.7627660797299;588.632104934416;607.7052629474701;7281.5995120267835;8294.129116442808;8307.626156822958;8372.33021358302;8479.872801907977;561.4077148608258;590.9343715239128;591.3672370563542;595.7408527290643;616.1374076293135;3122.98438507806;3745.9250814983548;3964.385520244367;4076.775777332216;4109.97945010279;98645.50677246711;98982.5050874741;101272;101415.98584014065;102383;76539.61730191314;76624.61687691638;77702.6114869422;79818.40363192704;83020;67712.66143669348;69919;70258;71247.64376178086;71289.64355178295;7756.883646745417;7789.766307010798;7797.7660670179985;7860.174681658344;7946.40401969843;52169.478305216464;52729.736351317995;52914.47085529096;53640.19539706985;53896;68859.96710049454;74046;74657.62671186609;75064.62467687626;75294.87057694029;10142.746431339276;10179.694609161737;10305;10335.741606459898;10489.842652360068;11011.229213954946;11062.668119956414;11921;12613.936930315656;12763.298018608693;11706.824397633873;12009.579664711693;12145.332006739362;12169.69575760613;12291.815622765487;11647.417629118341;11895.10786691019;11937.164398492023;12007.039436845205;12043.518259269531;10534.209934255116;10770.51532681047;10891.891081089405;11191;11568.710782230512;9824.312298139062;9871.16095131893;9893.604255829687;10111.54498047575;10168.847467288144;9582.377145485521;9799.363041402286;9804.36271642341;9861.95069024679;9959.850602241117 04:49:56 INFO - PROCESS | 1663 | __end_tp_report 04:49:56 INFO - PROCESS | 1663 | __start_cc_report 04:49:56 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,398 04:49:56 INFO - PROCESS | 1663 | __end_cc_report 04:49:56 INFO - PROCESS | 1663 | __startTimestamp1469447396330__endTimestamp 04:49:56 INFO - PROCESS | 1663 | 04:49:56 INFO - PROCESS | 1663 | ------- Summary: start ------- 04:49:56 INFO - PROCESS | 1663 | Number of tests: 6 04:49:56 INFO - PROCESS | 1663 | 04:49:56 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5968.04 Median:6223.56 stddev:700.59 (11.3%) stddev-sans-first:666.96 04:49:56 INFO - PROCESS | 1663 | Values: 3150.6 3713.3 3719.1 3743.7 3757.2 6063.8 6068.4 6071.2 6076.2 6094.7 5949.6 6074.4 6094.9 6097.1 6124.4 5370.2 5435.4 5470.1 5520.5 5567.3 5336.0 5454.8 5455.1 5518.1 5628.4 4230.9 4517.8 5214.8 5365.3 5560.4 3879.9 5485.2 5513.6 5541.0 5556.7 5270.1 5343.3 5364.1 5392.8 5442.7 4906.4 5917.8 5932.0 5961.6 6085.5 6223.6 6422.1 6431.3 6431.8 6436.1 6120.7 6513.7 6515.9 6522.6 6528.2 6027.6 6050.0 6078.8 6145.5 6177.6 6467.4 6568.2 6637.8 6648.0 6663.2 6265.1 6276.4 6331.2 6332.5 6346.0 6454.6 6464.5 6465.9 6466.5 6481.2 6228.5 6286.1 6312.3 6330.2 6369.6 6350.0 6367.4 6375.9 6377.8 6398.7 5098.5 5109.6 5154.1 5162.1 5167.1 4574.6 4588.1 4601.7 4651.7 4701.9 5385.6 6087.8 6203.8 6248.3 6269.9 6469.2 6496.0 6505.2 6512.9 6534.0 6149.6 6217.4 6239.6 6258.5 6265.5 5671.8 5683.3 5684.8 5693.8 5695.4 6743.1 6765.6 6779.6 6799.2 6808.4 6127.4 6430.5 6487.1 6526.6 6532.7 6735.8 6752.3 6771.5 6784.7 6791.4 6235.5 6344.6 6402.8 6410.4 6418.0 6323.5 6436.2 6464.6 6490.4 6511.9 6300.6 6304.1 6329.1 6339.8 6356.7 6188.6 6300.3 6309.0 6312.2 6340.0 4854.9 6115.2 6174.0 6222.9 6245.8 6213.3 6255.9 6261.3 6261.5 6271.9 6129.1 6156.4 6167.9 6190.5 6242.7 04:49:56 INFO - PROCESS | 1663 | 04:49:56 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:65474.70 Median:38399.71 stddev:143828.57 (374.6%) stddev-sans-first:144149.34 04:49:56 INFO - PROCESS | 1663 | Values: 510.3 531.8 539.5 565.7 625.6 40855.6 41929.0 41935.0 41956.2 41981.8 43281.6 43649.6 44505.6 44570.0 44587.3 9607.6 9754.6 9853.3 9866.2 9866.2 9756.8 9771.2 9844.7 9854.8 9856.8 9547.0 9766.0 9785.2 9808.6 9898.5 9764.6 9802.9 9868.2 9877.3 9882.9 9647.9 9659.2 9672.1 9775.1 9788.7 39783.2 39910.6 40248.6 40692.8 40778.4 2955.8 3190.7 3241.6 3328.8 3404.0 28044.6 29492.7 29740.1 30381.7 30462.7 2576.5 2586.7 2613.9 2671.9 2731.8 386720.0 387586.0 390282.0 391608.0 392975.0 37999.1 38691.6 38838.6 64537.4 65105.0 37337.8 37430.3 37618.4 37738.8 37812.6 15437.8 15571.0 15887.8 16016.0 16046.3 13063.9 13107.7 13109.2 13188.9 13246.9 8416.3 8703.7 8724.4 8748.4 8786.4 3650.2 3691.9 3708.7 3709.0 3718.0 995.3 1024.0 1058.1 1069.0 1106.4 28199.2 30798.4 31323.8 31361.4 31663.8 1079.2 1101.4 1113.6 1129.1 1163.8 12873.8 15189.8 15194.6 15364.9 15577.1 622129.0 624977.0 629488.0 640125.0 640313.0 58019.4 59627.0 60317.7 60329.7 60375.7 1287.4 1295.8 1538.9 1586.6 1614.3 27146.9 29790.0 30285.7 30890.0 30971.4 42688.6 48195.3 52173.0 54007.7 54554.0 496444.0 570279.0 572577.0 575671.0 590167.0 27298.0 27662.6 27749.6 27752.4 27998.3 45468.9 46025.8 46105.0 46837.8 47428.1 30131.7 37754.4 38011.0 38415.8 38687.4 37605.0 37789.8 38009.8 38305.2 38383.6 37764.8 38076.2 38287.2 38723.6 39038.6 32527.0 33820.0 34035.3 34173.5 34293.3 25550.7 25755.2 25856.7 26296.0 26324.5 04:49:56 INFO - PROCESS | 1663 | 04:49:56 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:37848.99 Median:12162.68 stddev:112055.26 (921.3%) stddev-sans-first:112334.24 04:49:56 INFO - PROCESS | 1663 | Values: 354.3 414.0 415.6 421.0 427.4 20740.8 21414.1 21435.0 21482.8 22721.5 21046.6 21521.9 21824.5 22114.6 22183.0 5911.1 5938.1 6233.0 6238.1 6249.9 6022.7 6150.1 6163.4 7038.2 7038.6 6909.2 6940.3 6987.2 7006.9 7008.2 6657.8 6859.1 6865.4 6869.3 6956.3 6057.9 6239.2 6250.5 6262.7 6271.4 22510.0 24355.9 26274.1 26970.5 27314.6 2210.8 2220.2 2295.9 2477.8 2535.0 10198.8 10250.3 10414.3 10750.3 10862.3 1811.3 1849.8 1896.0 1918.8 1992.1 119350.4 122688.4 123305.0 124954.4 125395.4 9964.5 11985.9 12151.3 12224.4 12282.6 15780.4 15798.9 15818.6 15884.0 15884.8 7403.4 8714.5 9259.6 9274.0 9331.3 9096.2 9215.6 9234.9 9248.9 9302.8 4813.7 4849.5 4862.1 4911.4 4934.1 2067.9 2119.6 2154.8 2167.2 2178.6 856.9 930.4 945.1 1010.2 1081.5 16749.0 18335.8 19014.6 21222.7 21772.3 904.3 929.3 1021.1 1052.5 1083.6 11132.1 11223.3 11299.4 11352.2 11385.1 656274.0 671183.0 673061.0 682197.0 687283.0 123926.0 129584.0 134058.0 137822.3 143223.0 1033.0 1037.7 1049.0 1049.4 1050.2 18834.8 19331.6 19381.9 19389.3 19612.3 52891.2 54024.2 55118.4 56731.0 56991.1 77531.6 79275.2 79745.6 80241.0 81960.2 8371.7 8452.5 8553.7 8680.5 8996.1 12527.4 12910.5 13027.5 13176.9 13214.5 10666.4 10984.6 11256.2 11465.1 11558.9 10893.6 11065.1 11258.0 11315.5 11364.6 11420.5 11563.1 11632.6 11749.4 11768.8 8059.6 8398.1 8439.3 8662.9 8834.6 8349.2 10295.8 11319.2 11504.8 11529.0 04:49:56 INFO - PROCESS | 1663 | 04:49:56 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:6001.38 Median:5102.87 stddev:3353.51 (65.7%) stddev-sans-first:3344.93 04:49:56 INFO - PROCESS | 1663 | Values: 1380.0 1597.6 1838.3 2013.3 2066.9 8509.1 9189.1 9341.4 9540.0 9555.4 9308.4 9312.8 9439.5 9451.0 9470.4 2514.0 2544.8 2554.4 2560.5 2568.6 2528.2 2578.2 2581.6 2609.7 2612.8 2558.7 2576.6 2590.4 2620.1 2626.1 2138.9 2176.9 2205.2 2321.4 2625.1 2568.0 2569.2 2603.0 2616.3 2640.0 9440.2 9525.7 9574.0 9579.4 9611.2 5698.6 5759.2 5783.3 5917.5 5917.6 3704.9 3779.5 3798.4 3807.5 3815.3 1072.4 1073.4 1078.3 1080.3 1087.6 12390.6 12400.6 12414.1 12414.7 12750.7 5096.0 5120.9 5197.5 5223.9 5259.2 10573.2 10603.2 10837.3 10868.7 10879.9 5685.6 5733.8 5749.3 5854.9 5900.4 4671.1 5109.8 5224.8 5228.3 5260.4 3511.6 4368.7 4427.0 4476.2 4483.3 1209.4 1423.2 1463.1 1464.4 1468.2 856.4 973.7 981.3 986.6 1007.7 6097.7 6184.8 6262.5 6304.2 6308.2 486.0 486.2 492.9 493.1 500.8 3633.9 3668.8 4086.4 4118.2 4174.0 9671.8 11159.4 11569.3 11624.0 11674.6 9327.6 10937.3 10974.8 10984.5 11109.6 8414.4 8629.8 8795.0 8849.0 8957.0 4931.3 5490.5 6192.7 6202.9 6266.8 10082.8 10212.2 10241.7 10263.8 10373.0 8830.5 9991.1 10088.9 10397.6 10426.9 7273.5 7720.4 7922.9 7994.0 8010.8 7305.5 8754.6 8792.3 8809.6 8835.7 7126.9 7419.5 7802.1 7855.6 7925.6 7630.7 7701.9 7723.5 7783.5 7801.6 7374.9 7442.5 7532.7 7568.7 7584.5 5085.0 5122.2 5194.6 5270.9 5275.8 5270.6 5304.1 5354.1 5468.9 5483.3 04:49:56 INFO - PROCESS | 1663 | 04:49:56 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:6109.18 Median:6935.22 stddev:2013.41 (29.0%) stddev-sans-first:1971.86 04:49:56 INFO - PROCESS | 1663 | Values: 334.7 341.0 344.2 347.3 350.3 6339.3 6930.3 6959.0 6998.1 7011.5 5508.0 6904.8 6925.2 6933.5 6936.9 4939.7 5044.1 5125.6 5134.7 5163.4 4954.4 5149.5 5169.9 5199.3 5217.4 4566.7 5178.7 5182.7 5197.6 5202.3 5174.3 5187.1 5190.2 5204.7 5222.1 4467.0 4467.1 4470.4 4474.2 4474.4 5368.6 6509.3 6704.8 6767.3 6805.7 5964.6 5970.7 5996.5 6000.6 6022.6 6011.6 6839.7 7184.0 7489.8 7506.2 4780.0 4782.2 4790.4 4796.2 4802.9 7606.5 7896.0 7937.3 7974.9 7981.2 4141.3 4851.3 4869.0 4882.6 4892.0 4933.7 4942.8 4943.7 4950.6 4950.6 4968.0 5823.6 5945.1 5961.1 5963.4 7479.0 7482.6 7485.4 7487.0 7494.1 1662.4 1696.6 1727.9 1733.5 1737.1 866.3 887.9 1002.8 1080.4 1104.2 7247.3 7257.3 7262.2 7274.7 7283.3 6260.9 7440.4 7692.6 7747.0 7793.5 7259.3 7269.9 7272.9 7322.2 7327.6 3322.0 4096.4 4103.0 4144.0 4149.3 8207.0 8223.6 8225.0 8335.2 8374.9 7837.4 8077.9 8159.7 8160.0 8163.1 8300.3 8301.8 8318.5 8320.9 8331.0 7836.3 7904.3 7921.8 7927.0 7937.0 6189.4 7401.7 7745.0 7769.2 7773.2 8283.3 8306.7 8314.3 8331.5 8350.1 4125.4 4873.5 4879.4 4918.3 4924.9 7860.1 7868.2 7871.5 7884.2 7901.0 7276.9 7297.5 7571.4 7580.6 7588.9 6121.6 6534.7 7559.4 7603.2 7615.9 5989.4 7439.1 7440.8 7452.0 7458.3 7545.9 7570.4 7600.6 7602.4 7612.0 7221.9 7357.2 7377.5 7453.5 7465.8 04:49:56 INFO - PROCESS | 1663 | 04:49:56 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:18846.31 Median:568.97 stddev:26746.23 (4700.8%) stddev-sans-first:26786.59 04:49:56 INFO - PROCESS | 1663 | Values: 708.8 721.9 737.8 787.1 802.4 15606.4 15613.4 15638.0 16005.5 16029.4 14388.3 14751.3 14771.9 14785.3 14786.3 751.3 910.1 913.5 917.9 927.3 878.5 881.1 902.3 914.1 954.2 941.3 941.9 942.0 943.9 1013.4 933.6 934.7 971.1 973.5 1010.1 724.2 741.3 747.9 751.7 753.6 12882.0 15535.1 15701.2 15735.8 15823.9 13153.7 13334.5 13410.9 13417.6 13483.8 10116.0 10196.4 10223.8 10353.5 10490.3 3573.1 3586.9 3591.6 3643.4 3682.7 59229.0 82669.0 82724.0 83004.6 83513.0 9568.7 9676.7 9953.2 10146.0 10421.8 23059.7 23509.1 23607.8 23938.5 24095.0 5959.7 6052.8 6115.2 6131.2 6230.9 4126.1 4165.5 4228.8 4251.4 4266.7 3645.2 3691.9 3760.6 3764.8 3765.0 652.7 670.9 672.5 676.2 676.6 533.2 576.5 585.8 588.6 607.7 7281.6 8294.1 8307.6 8372.3 8479.9 561.4 590.9 591.4 595.7 616.1 3123.0 3745.9 3964.4 4076.8 4110.0 98645.5 98982.5 101272.0 101416.0 102383.0 76539.6 76624.6 77702.6 79818.4 83020.0 67712.7 69919.0 70258.0 71247.6 71289.6 7756.9 7789.8 7797.8 7860.2 7946.4 52169.5 52729.7 52914.5 53640.2 53896.0 68860.0 74046.0 74657.6 75064.6 75294.9 10142.7 10179.7 10305.0 10335.7 10489.8 11011.2 11062.7 11921.0 12613.9 12763.3 11706.8 12009.6 12145.3 12169.7 12291.8 11647.4 11895.1 11937.2 12007.0 12043.5 10534.2 10770.5 10891.9 11191.0 11568.7 9824.3 9871.2 9893.6 10111.5 10168.8 9582.4 9799.4 9804.4 9862.0 9959.9 04:49:56 INFO - PROCESS | 1663 | -------- Summary: end -------- 04:49:56 INFO - PROCESS | 1663 | 04:49:56 INFO - TEST-INFO | 1663: exit 0 04:49:56 INFO - TEST-OK | dromaeo_css | took 1110574ms 04:49:56 INFO - TEST-START | kraken 04:49:56 INFO - operating with platform_type : mac_ 04:49:56 INFO - Initialising browser for kraken test... 04:49:56 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpKF4VQp/profile http://localhost:49277/getInfo.html) 04:49:59 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 04:49:59 INFO - PROCESS | 1675 | colorDepth:24 04:49:59 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 04:49:59 INFO - PROCESS | 1675 | __metrics 04:49:59 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 04:50:00 INFO - TEST-INFO | 1675: exit 0 04:50:00 INFO - Browser initialized. 04:50:00 INFO - Running cycle 1/1 for kraken test... 04:50:00 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpKF4VQp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:50:11 INFO - PROCESS | 1679 | RSS: Main: 171024384 04:50:11 INFO - PROCESS | 1679 | 04:50:14 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 04:50:15 INFO - PROCESS | 1679 | RSS: Main: 183521280 04:50:15 INFO - PROCESS | 1679 | 04:50:18 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 04:50:18 INFO - PROCESS | 1679 | RSS: Main: 192258048 04:50:18 INFO - PROCESS | 1679 | 04:50:22 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 04:50:22 INFO - PROCESS | 1679 | RSS: Main: 196390912 04:50:22 INFO - PROCESS | 1679 | 04:50:24 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 04:50:25 INFO - PROCESS | 1679 | RSS: Main: 196546560 04:50:25 INFO - PROCESS | 1679 | 04:50:27 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:50:28 INFO - PROCESS | 1679 | RSS: Main: 196919296 04:50:28 INFO - PROCESS | 1679 | 04:50:30 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 04:50:31 INFO - PROCESS | 1679 | RSS: Main: 197173248 04:50:31 INFO - PROCESS | 1679 | 04:50:34 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 04:50:35 INFO - PROCESS | 1679 | RSS: Main: 196952064 04:50:35 INFO - PROCESS | 1679 | 04:50:38 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 04:50:38 INFO - PROCESS | 1679 | RSS: Main: 198410240 04:50:38 INFO - PROCESS | 1679 | 04:50:40 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:50:40 INFO - PROCESS | 1679 | RSS: Main: 197668864 04:50:40 INFO - PROCESS | 1679 | 04:50:42 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:50:43 INFO - PROCESS | 1679 | RSS: Main: 197865472 04:50:43 INFO - PROCESS | 1679 | 04:50:45 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:50:46 INFO - PROCESS | 1679 | RSS: Main: 198037504 04:50:46 INFO - PROCESS | 1679 | 04:50:48 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:50:49 INFO - PROCESS | 1679 | RSS: Main: 198291456 04:50:49 INFO - PROCESS | 1679 | 04:50:52 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:50:53 INFO - PROCESS | 1679 | RSS: Main: 198193152 04:50:53 INFO - PROCESS | 1679 | 04:50:54 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 04:50:55 INFO - PROCESS | 1679 | RSS: Main: 200192000 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - PROCESS | 1679 | __start_tp_report 04:50:55 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 04:50:55 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 04:50:55 INFO - PROCESS | 1679 | |i|pagename|runs| 04:50:55 INFO - PROCESS | 1679 | |0;ai-astar;114;110;116;117;116;133;116;115;116;117 04:50:55 INFO - PROCESS | 1679 | |1;audio-beat-detection;150;118;140;159;122;169;172;124;167;122 04:50:55 INFO - PROCESS | 1679 | |2;audio-dft;201;195;193;194;192;222;180;264;273;204 04:50:55 INFO - PROCESS | 1679 | |3;audio-fft;63;81;77;141;75;55;58;70;127;83 04:50:55 INFO - PROCESS | 1679 | |4;audio-oscillator;91;80;125;76;79;98;145;80;82;141 04:50:55 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;89;86;90;107;88;83;81;116;76;76 04:50:55 INFO - PROCESS | 1679 | |6;imaging-darkroom;151;126;95;95;93;95;95;121;148;97 04:50:55 INFO - PROCESS | 1679 | |7;imaging-desaturate;145;150;90;92;89;120;142;91;90;91 04:50:55 INFO - PROCESS | 1679 | |8;json-parse-financial;56;61;63;68;56;57;62;77;60;57 04:50:55 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;63;66;65;72;67;70;67;68;65;65 04:50:55 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;82;81;81;79;79;81;110;106;107;111 04:50:55 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;128;131;147;149;129;127;145;145;152;166 04:50:55 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;224;158;221;200;164;167;159;166;165;165 04:50:55 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;55;97;61;80;101;100;72;65;98;97 04:50:55 INFO - PROCESS | 1679 | __end_tp_report 04:50:55 INFO - PROCESS | 1679 | __start_cc_report 04:50:55 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,1045 04:50:55 INFO - PROCESS | 1679 | __end_cc_report 04:50:55 INFO - PROCESS | 1679 | __startTimestamp1469447455128__endTimestamp 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - PROCESS | 1679 | ------- Summary: start ------- 04:50:55 INFO - PROCESS | 1679 | Number of tests: 14 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:117.00 Median:116.00 stddev:5.98 (5.2%) stddev-sans-first:6.24 04:50:55 INFO - PROCESS | 1679 | Values: 114.0 110.0 116.0 117.0 116.0 133.0 116.0 115.0 116.0 117.0 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:144.30 Median:154.50 stddev:21.75 (14.1%) stddev-sans-first:22.97 04:50:55 INFO - PROCESS | 1679 | Values: 150.0 118.0 140.0 159.0 122.0 169.0 172.0 124.0 167.0 122.0 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:211.80 Median:202.50 stddev:31.81 (15.7%) stddev-sans-first:33.50 04:50:55 INFO - PROCESS | 1679 | Values: 201.0 195.0 193.0 194.0 192.0 222.0 180.0 264.0 273.0 204.0 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:83.00 Median:72.50 stddev:28.64 (39.5%) stddev-sans-first:29.45 04:50:55 INFO - PROCESS | 1679 | Values: 63.0 81.0 77.0 141.0 75.0 55.0 58.0 70.0 127.0 83.0 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:99.70 Median:80.00 stddev:26.99 (33.7%) stddev-sans-first:28.44 04:50:55 INFO - PROCESS | 1679 | Values: 91.0 80.0 125.0 76.0 79.0 98.0 145.0 80.0 82.0 141.0 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:89.20 Median:84.50 stddev:12.92 (15.3%) stddev-sans-first:13.70 04:50:55 INFO - PROCESS | 1679 | Values: 89.0 86.0 90.0 107.0 88.0 83.0 81.0 116.0 76.0 76.0 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:111.60 Median:95.00 stddev:23.18 (24.4%) stddev-sans-first:19.72 04:50:55 INFO - PROCESS | 1679 | Values: 151.0 126.0 95.0 95.0 93.0 95.0 95.0 121.0 148.0 97.0 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:110.00 Median:90.00 stddev:26.32 (29.2%) stddev-sans-first:24.69 04:50:55 INFO - PROCESS | 1679 | Values: 145.0 150.0 90.0 92.0 89.0 120.0 142.0 91.0 90.0 91.0 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:61.70 Median:61.50 stddev:6.57 (10.7%) stddev-sans-first:6.63 04:50:55 INFO - PROCESS | 1679 | Values: 56.0 61.0 63.0 68.0 56.0 57.0 62.0 77.0 60.0 57.0 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:67.00 stddev:2.66 (4.0%) stddev-sans-first:2.44 04:50:55 INFO - PROCESS | 1679 | Values: 63.0 66.0 65.0 72.0 67.0 70.0 67.0 68.0 65.0 65.0 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:91.70 Median:80.00 stddev:14.55 (18.2%) stddev-sans-first:15.01 04:50:55 INFO - PROCESS | 1679 | Values: 82.0 81.0 81.0 79.0 79.0 81.0 110.0 106.0 107.0 111.0 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:141.90 Median:146.00 stddev:12.82 (8.8%) stddev-sans-first:12.57 04:50:55 INFO - PROCESS | 1679 | Values: 128.0 131.0 147.0 149.0 129.0 127.0 145.0 145.0 152.0 166.0 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.90 Median:166.50 stddev:25.82 (15.5%) stddev-sans-first:21.62 04:50:55 INFO - PROCESS | 1679 | Values: 224.0 158.0 221.0 200.0 164.0 167.0 159.0 166.0 165.0 165.0 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.60 Median:76.00 stddev:18.11 (23.8%) stddev-sans-first:16.22 04:50:55 INFO - PROCESS | 1679 | Values: 55.0 97.0 61.0 80.0 101.0 100.0 72.0 65.0 98.0 97.0 04:50:55 INFO - PROCESS | 1679 | -------- Summary: end -------- 04:50:55 INFO - PROCESS | 1679 | 04:50:55 INFO - TEST-INFO | 1679: exit 0 04:50:55 INFO - TEST-OK | kraken | took 59003ms 04:50:55 INFO - SUITE-END | took 1169s 04:50:56 INFO - Completed test suite (00:19:29) 04:50:56 INFO - javascript benchmark 04:50:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3150.5589217509546, 3713.29447404993, 3719.107414220588, 3743.66307032367, 3757.210985693003, 6063.787767428141, 6068.423499767526, 6071.180390647261, 6076.21009268795, 6094.725737341819, 5949.613275137124, 6074.392560743923, 6094.908576371358, 6097.146399504073, 6124.387561243884, 5370.194470829368, 5435.42927992561, 5470.070088085035, 5520.503154716075, 5567.331920169585, 5336.012837625051, 5454.836354909339, 5455.099908515091, 5518.061929471979, 5628.380878103405, 4230.894227644303, 4517.751523666214, 5214.843554693365, 5365.3367456088545, 5560.443955604427, 3879.9030024249337, 5485.204645326406, 5513.58648101394, 5541.0, 5556.666600004012, 5270.130428479296, 5343.332083489564, 5364.088105022156, 5392.757325920343, 5442.727863606804, 4906.386701662293, 5917.763289468416, 5932.021216499273, 5961.642301461926, 6085.4827339676185, 6223.564350495467, 6422.068800023971, 6431.324710905381, 6431.774887878902, 6436.098946147543, 6120.693965301717, 6513.674316284167, 6515.902261466082, 6522.608643481359, 6528.21661400635, 6027.638341699469, 6050.0, 6078.848028799272, 6145.477634401082, 6177.598456100339, 6467.353264673589, 6568.1789776277965, 6637.800865974028, 6648.03603477493, 6663.233728121231, 6265.122882796412, 6276.372362763687, 6331.2085989251345, 6332.461740751995, 6346.0, 6454.612723236621, 6464.450521705614, 6465.935340646627, 6466.547341686037, 6481.222253329536, 6228.4705800007505, 6286.088517165077, 6312.337204593543, 6330.240371155491, 6369.649669268142, 6350.047492876106, 6367.363263673596, 6375.936240637534, 6377.840553986188, 6398.680065996681, 5098.490150984946, 5109.642325037286, 5154.123798954187, 5162.148245539519, 5167.069927413027, 4574.6340292776495, 4588.082383523242, 4601.67788254819, 4651.720896746206, 4701.905961880743, 5385.569154467633, 6087.786927457606, 6203.7828675996125, 6248.28144763349, 6269.8746025079245, 6469.223693156757, 6496.0, 6505.154329937078, 6512.869742605121, 6533.987231979051, 6149.631022138686, 6217.440430361289, 6239.594426362272, 6258.468030217391, 6265.498760099181, 5671.829845104653, 5683.2895888014, 5684.77260909559, 5693.829185124453, 5695.430456954271, 6743.123393958852, 6765.627890466071, 6779.559328643622, 6799.184097908084, 6808.38724514796, 6127.433875357673, 6430.549861509649, 6487.124238227779, 6526.608403495806, 6532.706028228646, 6735.831604209934, 6752.324767523405, 6771.52599318043, 6784.660766961533, 6791.422729067887, 6235.469985051322, 6344.587601805868, 6402.807915762534, 6410.358964103552, 6418.0, 6323.525735569759, 6436.227652681709, 6464.644444555406, 6490.383413575702, 6511.902321465087, 6300.558960872878, 6304.117423560797, 6329.145565348619, 6339.778107766206, 6356.745730170742, 6188.566800323934, 6300.306966233802, 6308.968455157695, 6312.179416675803, 6340.048992651139, 4854.854354369375, 6115.205023347115, 6174.0, 6222.875542489034, 6245.781397651061, 6213.254409470893, 6255.906161407492, 6261.3112557617715, 6261.467775238973, 6271.874562508633, 6129.083728908335, 6156.415140561639, 6167.907481387693, 6190.473809726223, 6242.687865606792], "value": 5924.689233236972, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.2652180859564, 531.7713383245203, 539.5063516882053, 565.6860442454438, 625.5714835337794, 40855.59144408555, 41929.0, 41935.0, 41956.160876782444, 41981.79009104958, 43281.567184328145, 43649.563504364945, 44505.554944450625, 44570.0, 44587.33119003209, 9607.615695372195, 9754.561044753003, 9853.310268281222, 9866.16137628299, 9866.210703143732, 9756.756081097958, 9771.169450596672, 9844.70465886021, 9854.753631159243, 9856.753581160492, 9547.045295470467, 9766.0, 9785.217182625409, 9808.607655693764, 9898.50507474627, 9764.632951386811, 9802.852957205647, 9868.161206297402, 9877.308588398779, 9882.901170988342, 9647.90352096477, 9659.178969787576, 9672.08115229052, 9775.071368220008, 9788.706338809847, 39783.20433591341, 39910.60089399098, 40248.597514025074, 40692.79653601743, 40778.38832417516, 2955.7783166262616, 3190.6968837960358, 3241.5623890774914, 3328.766986310959, 3403.9659603403898, 28044.579331310048, 29492.70507294921, 29740.107796766133, 30381.69618303833, 30462.69537304643, 2576.536223479773, 2586.741325867417, 2613.9346516337055, 2671.919842404731, 2731.808773385844, 386720.0, 387586.0, 390282.04858975805, 391608.0, 392974.99999999715, 37999.05002374963, 38691.613083869364, 38838.611613884066, 64537.35462645313, 65105.023424648665, 37337.81331093327, 37430.251394972496, 37618.43572346417, 37738.811305943294, 37812.62187378146, 15437.84562154364, 15571.0, 15887.841121588868, 16016.0, 16046.27791749374, 13063.869361306266, 13107.672308192181, 13109.213447193199, 13188.934055329662, 13246.867531324564, 8416.326693864476, 8703.695370662123, 8724.43291186071, 8748.387612867038, 8786.42888212277, 3650.1969566695284, 3691.8523259069875, 3708.6593415339134, 3709.0, 3717.9628203717616, 995.2535598301274, 1023.9692809215735, 1058.0847566854616, 1068.9874423779872, 1106.4080716816625, 28199.15402537927, 30798.384032319227, 31323.84338078295, 31361.372772544877, 31663.841680791447, 1079.233744041739, 1101.372217835826, 1113.5768408004906, 1129.079799963044, 1163.8410748062392, 12873.806892896613, 15189.848101519065, 15194.620134496727, 15364.923175384052, 15577.143257120968, 622129.0, 624977.0, 629488.0, 640125.0, 640313.0, 58019.4198058014, 59627.0, 60317.69841150766, 60329.69835150796, 60375.69812150999, 1287.3756228229302, 1295.8185853980451, 1538.9384424623113, 1586.6112802363264, 1614.31391658547, 27146.864265678545, 29789.957351492594, 30285.69714302917, 30890.0, 30971.380572388876, 42688.573114269704, 48195.277070843265, 52172.95654086973, 54007.72996135151, 54554.0, 496444.0, 570279.0, 572577.0, 575671.0, 590167.0, 27298.04456844001, 27662.5850612245, 27749.583756243268, 27752.444951101268, 27998.3000424991, 45468.86327841698, 46025.76987115043, 46105.0, 46837.76581117073, 47428.05143897034, 30131.698683013765, 37754.43368349532, 38011.0, 38415.807920960215, 38687.41968870413, 37604.97915378372, 37789.81105094457, 38009.80995095007, 38305.2338953225, 38383.61616383912, 37764.81117594394, 38076.23847523089, 38287.23425531418, 38723.612763872, 39038.6096139035, 32527.0, 33819.985400438025, 34035.319293614484, 34173.487397689554, 34293.314133717686, 25550.74449255558, 25755.227343179733, 25856.741432585433, 26296.0, 26324.473510530064], "value": 18744.338394027105, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.26490033181153, 413.95446500884896, 415.6404709925913, 420.9705320627556, 427.36749610576345, 20740.792592074093, 21414.14343426265, 21435.0, 21482.785172148273, 22721.54556908865, 21046.57906841862, 21521.923903804785, 21824.454388640293, 22114.557708845812, 22183.0, 5911.113333000063, 5938.13896984938, 6232.968835155841, 6238.064290356438, 6249.875002499947, 6022.6687532185715, 6150.077488376734, 6163.414475624831, 7038.15542134942, 7038.6128762918015, 6909.239983601825, 6940.30596940307, 6987.161540615133, 7006.859862802767, 7008.229094799568, 6657.766978155765, 6859.108315918948, 6865.41643960264, 6869.347411995853, 6956.304369563054, 6057.939420605826, 6239.188905442309, 6250.531210159256, 6262.655553944577, 6271.435570798604, 22510.0, 24355.878220608785, 26274.080407185847, 26970.460590788076, 27314.5902811458, 2210.801027907496, 2220.222921977311, 2295.931122066341, 2477.7893879020303, 2535.0, 10198.796024079551, 10250.333728307636, 10414.323069000491, 10750.301230420077, 10862.3482591044, 1811.3479147506887, 1849.805770394116, 1895.981040189608, 1918.8464922806143, 1992.1234656750983, 119350.40324798407, 122688.38655806753, 123305.0, 124954.37522812419, 125395.37302313521, 9964.501774911225, 11985.880141198651, 12151.270923744603, 12224.388780560936, 12282.631521054383, 15780.368785248691, 15798.9210053949, 15818.60453488649, 15884.046957182605, 15884.76172857408, 7403.444741644398, 8714.477131372138, 9259.629614815467, 9274.0, 9331.300152488591, 9096.1813436791, 9215.585298661443, 9234.86147707785, 9248.907510924939, 9302.813943721088, 4813.663043586986, 4849.4908034656555, 4862.100511405401, 4911.435184953776, 4934.136526107916, 2067.9483012924497, 2119.6184686756533, 2154.795294447025, 2167.198136689435, 2178.553396553703, 856.9160012584086, 930.3766476460787, 945.0974319524975, 1010.2170817616407, 1081.507913899174, 16749.0, 18335.816641833677, 19014.573906957034, 21222.67565560983, 21772.346829594822, 904.2856143646445, 929.2565947242314, 1021.1269364693229, 1052.5105825791045, 1083.6075642798965, 11132.053775429018, 11223.270487418291, 11299.44063157061, 11352.177310577188, 11385.08919286455, 656274.0, 671183.0, 673061.0, 682197.0, 687283.0, 123926.0, 129584.0, 134058.0, 137822.31088844492, 143223.0, 1032.9586816527255, 1037.6679462572056, 1049.0296475759892, 1049.4123290957089, 1050.2018465966073, 18834.811651883854, 19331.613367732287, 19381.90309048446, 19389.321373751853, 19612.313569025016, 52891.206631901325, 54024.18963715469, 55118.44881551133, 56731.0, 56991.14513282221, 77531.61234193793, 79275.20724792678, 79745.60127199326, 80241.0, 81960.18039819764, 8371.748847534584, 8452.492850428995, 8553.74338769813, 8680.522571258642, 8996.145366190201, 12527.37363131858, 12910.548130815376, 13027.47890084386, 13176.934115329363, 13214.537491187762, 10666.360018398611, 10984.560617575207, 11256.21206515536, 11465.140114491613, 11558.942205289257, 10893.564257429614, 11065.114790816877, 11258.043066339125, 11315.547378104784, 11364.602238921598, 11420.543178273108, 11563.074954003827, 11632.592859249886, 11749.353785541878, 11768.823467648162, 8059.556724380215, 8398.118197589165, 8439.324854011542, 8662.91337086621, 8834.64661413562, 8349.206825351583, 10295.845562316421, 11319.151063670093, 11504.827427588425, 11529.0], "value": 10066.984964479137, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1379.979300310495, 1597.5686564627547, 1838.3106335124328, 2013.345662659636, 2066.85532012759, 8509.064002959669, 9189.127032931861, 9341.439513629179, 9540.045995400465, 9555.426674399532, 9308.441493510378, 9312.81374372512, 9439.480828554444, 9451.007644197363, 9470.38442501237, 2514.006967247936, 2544.847309161447, 2554.441790507789, 2560.4623029163804, 2568.563344231485, 2528.2162529615784, 2578.2007577650893, 2581.5611346071214, 2609.712931577525, 2612.830166039211, 2558.7185409604926, 2576.5828794163135, 2590.4042070323835, 2620.082970960158, 2626.1333759859294, 2138.8823614701187, 2176.889786209029, 2205.162038425396, 2321.4312493439047, 2625.0812215724536, 2568.0, 2569.1521797806677, 2603.0498867913266, 2616.2936007277926, 2640.049582150424, 9440.150386465182, 9525.714228573153, 9574.0, 9579.377340472916, 9611.231101511863, 5698.601097923147, 5759.222504961818, 5783.306003279633, 5917.467427931464, 5917.615355001911, 3704.8888533344043, 3779.45197946299, 3798.4302354646748, 3807.5240594925635, 3815.313243616148, 1072.4423299884018, 1073.414988831089, 1078.266778590558, 1080.308602494404, 1087.6302057300556, 12390.56633017849, 12400.56598019074, 12414.131010829336, 12414.689632759253, 12750.661180575999, 5095.97452012745, 5120.92318615221, 5197.506236907488, 5223.9477605223465, 5259.237410575445, 10573.207009474167, 10603.204759643058, 10837.295575787548, 10868.728281793019, 10879.945600272107, 5685.60200785941, 5733.827985160452, 5749.252597162342, 5854.912176317359, 5900.43945825146, 4671.135839869635, 5109.770060347293, 5224.843254702365, 5228.320318358665, 5260.421353651095, 3511.5610548681416, 4368.672349573794, 4427.0, 4476.194285028726, 4483.349914262414, 1209.4013463335705, 1423.195894319706, 1463.0855715178013, 1464.3703207620613, 1468.2014532197973, 856.3577317012241, 973.6786860336072, 981.3326937682444, 986.5807032011367, 1007.652359935821, 6097.69511524422, 6184.783532576428, 6262.538767504526, 6304.180456540712, 6308.179936608211, 485.980560777572, 486.20020066989605, 492.93838270216224, 493.13454886673657, 500.82971789591716, 3633.8546458141377, 3668.825975687754, 4086.40747091676, 4118.155778065489, 4173.979130104391, 9671.839379274392, 11159.38623375722, 11569.305841649528, 11623.953844154066, 11674.591389301504, 9327.580258888367, 10937.289076210021, 10974.780504389868, 10984.450777461096, 11109.555617775199, 8414.410991230556, 8629.78425539354, 8794.988576313677, 8848.955755221312, 8957.014728379872, 4931.334269873664, 5490.450954904637, 6192.659403732658, 6202.906956395567, 6266.843328916813, 10082.848757268794, 10212.183025358103, 10241.74395640115, 10263.846042309222, 10373.0, 8830.514321712368, 9991.050850169222, 10088.949555252177, 10397.584096636352, 10426.895731042592, 7273.454490913097, 7720.420968427503, 7922.920770792219, 7994.0407151142235, 8010.759677209693, 7305.488615796843, 8754.60604272822, 8792.29661627081, 8809.559522023745, 8835.734927952171, 7126.928730712834, 7419.4790068034845, 7802.102758182881, 7855.60721963888, 7925.643346049557, 7630.694772209272, 7701.92298077012, 7723.497972631761, 7783.532988020737, 7801.648925798466, 7374.889376659247, 7442.479026468095, 7532.661030253542, 7568.735094271894, 7584.54492730438, 5085.0, 5122.231665250242, 5194.558462530727, 5270.89458210826, 5275.788968441219, 5270.552003079782, 5304.124819404909, 5354.11657076578, 5468.9179662304305, 5483.314585676791], "value": 4763.304326377208, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.66700632870294, 340.9863605455782, 344.21347221598654, 347.32098746949714, 350.32212668486466, 6339.334369891153, 6930.341617546338, 6959.025736396902, 6998.125234345707, 7011.509194356391, 5507.972460137704, 6904.75833345833, 6925.168979722441, 6933.549319294242, 6936.895946560806, 4939.654224204307, 5044.117279476095, 5125.641205115643, 5134.7175905325375, 5163.380394352683, 4954.439351604233, 5149.485051494858, 5169.940162266745, 5199.324087868572, 5217.426083130853, 4566.703164294327, 5178.715170665612, 5182.740862956837, 5197.558207552363, 5202.271681964528, 5174.301469301652, 5187.092258854705, 5190.221466779994, 5204.687718736888, 5222.0861349264105, 4467.01725620363, 4467.061916997434, 4470.41884555009, 4474.239379305526, 4474.418325617681, 5368.570514358881, 6509.3490650935, 6704.765333213363, 6767.255601883789, 6805.706915686053, 5964.552658550626, 5970.6716130612795, 5996.460318571306, 6000.5799594028, 6022.603499445386, 6011.57918945674, 6839.692213850339, 7184.0, 7489.812754681122, 7506.249375062504, 4779.952200477986, 4782.1631214537665, 4790.42514898211, 4796.232602783538, 4802.855914322576, 7606.467547271694, 7896.0, 7937.325327347183, 7974.880376794353, 7981.241782030698, 4141.337386018223, 4851.272309153655, 4868.95131048692, 4882.633802464758, 4892.0, 4933.728644924492, 4942.777575009133, 4943.65394422387, 4950.554450099508, 4950.603951683928, 4968.0, 5823.5923485355615, 5945.078512830518, 5961.076033214858, 5963.373845746221, 7478.962605186939, 7482.5884576347735, 7485.401167906554, 7487.0, 7494.100707914976, 1662.401535447238, 1696.6437048220012, 1727.853132483753, 1733.4886208568503, 1737.1140718233792, 866.332923648787, 887.924526415249, 1002.8467262647868, 1080.4171888183848, 1104.1994553948484, 7247.311505406978, 7257.346838784535, 7262.20115787263, 7274.672639731225, 7283.2716728327805, 6260.937390626126, 7440.3675687567165, 7692.576908269987, 7746.9612651937505, 7793.454458187985, 7259.346658800733, 7269.8546029079125, 7272.890906636405, 7322.231165727522, 7327.5969821659255, 3321.9501707474406, 4096.3855421686385, 4102.958970410317, 4144.046869220036, 4149.2738770715005, 8207.0, 8223.629936652866, 8224.958875205586, 8335.166483351617, 8374.916250837536, 7837.373010159174, 8077.8788318175275, 8159.71440999574, 8160.0, 8163.0612479564425, 8300.294474969574, 8301.750947471464, 8318.500889946623, 8320.91828062348, 8331.0, 7836.333911617577, 7904.2886140245355, 7921.841563168819, 7927.0, 7936.960315198618, 6189.381061893775, 7401.740939067106, 7745.0, 7769.184235655175, 7773.183815699496, 8283.337333013224, 8306.70926517569, 8314.251717345225, 8331.458455200393, 8350.081491036102, 4125.370478660972, 4873.537013983666, 4879.39007624047, 4918.336024636628, 4924.926126108183, 7860.0567931848545, 7868.213178682315, 7871.48835325702, 7884.21157884207, 7901.012373453318, 7276.927230727625, 7297.525660832029, 7571.432142589218, 7580.583067931317, 7588.886166707614, 6121.632702037892, 6534.705938232886, 7559.357454616419, 7603.20166382544, 7615.885761713468, 5989.401059893976, 7439.070116235471, 7440.776776696708, 7451.993980812737, 7458.328750412489, 7545.886811697719, 7570.356519695888, 7600.619969001417, 7602.3537999268465, 7612.0, 7221.883182077665, 7357.227491113356, 7377.5204611700065, 7453.515521491086, 7465.813354666177], "value": 5425.431064222891, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.8410448916021, 721.8628460592487, 737.7749786315172, 787.1223585701943, 802.3861745764488, 15606.375744970215, 15613.375464981402, 15638.0, 16005.519834404971, 16029.358825646974, 14388.280585970711, 14751.336189871454, 14771.852281477182, 14785.334659940301, 14786.334614942327, 751.2825251884437, 910.1308250620641, 913.488446469979, 917.9173874351324, 927.2628260532858, 878.5387671472496, 881.066069965835, 902.2917010147036, 914.1270087066869, 954.2080073538981, 941.2940294778916, 941.948192849393, 941.9623215071389, 943.9244860411151, 1013.4274135113637, 933.5705575435308, 934.7476181430976, 971.0677749360623, 973.5181085362719, 1010.0707349238718, 724.199759266014, 741.2994719989612, 747.8840779679158, 751.6993202718902, 753.6081237756391, 12882.033847461384, 15535.067895926168, 15701.214939252992, 15735.763963540556, 15823.920880395639, 13153.73692526144, 13334.466621335136, 13410.86589134106, 13417.597472075853, 13483.797743033765, 10116.038976297314, 10196.439195844225, 10223.795524089552, 10353.482325883675, 10490.31812932157, 3573.1245844767936, 3586.910327241814, 3591.6049234584175, 3643.4352675335367, 3682.723795715332, 59228.99999999957, 82669.0, 82724.0, 83004.58497707533, 83513.0, 9568.665096721581, 9676.70969870905, 9953.153981911619, 10146.0, 10421.791564168674, 23059.6541051881, 23509.059637614646, 23607.81960901948, 23938.52122957531, 24095.0, 5959.702014899324, 6052.848678783065, 6115.205023346936, 6131.172291740647, 6230.937690623126, 4126.09225970286, 4165.522886418886, 4228.767417792051, 4251.404803327537, 4266.722663026893, 3645.179834537208, 3691.9261614768093, 3760.623937606272, 3764.811759412073, 3764.981175094107, 652.670401447266, 670.9060731497594, 672.4855485553555, 676.2088356622763, 676.5940435738518, 533.1868899927641, 576.5416493887286, 585.7627660797299, 588.632104934416, 607.7052629474701, 7281.5995120267835, 8294.129116442808, 8307.626156822958, 8372.33021358302, 8479.872801907977, 561.4077148608258, 590.9343715239128, 591.3672370563542, 595.7408527290643, 616.1374076293135, 3122.98438507806, 3745.9250814983548, 3964.385520244367, 4076.775777332216, 4109.97945010279, 98645.50677246711, 98982.5050874741, 101272.0, 101415.98584014065, 102383.0, 76539.61730191314, 76624.61687691638, 77702.6114869422, 79818.40363192704, 83020.0, 67712.66143669348, 69919.0, 70258.0, 71247.64376178086, 71289.64355178295, 7756.883646745417, 7789.766307010798, 7797.7660670179985, 7860.174681658344, 7946.40401969843, 52169.478305216464, 52729.736351317995, 52914.47085529096, 53640.19539706985, 53896.0, 68859.96710049454, 74046.0, 74657.62671186609, 75064.62467687626, 75294.87057694029, 10142.746431339276, 10179.694609161737, 10305.0, 10335.741606459898, 10489.842652360068, 11011.229213954946, 11062.668119956414, 11921.0, 12613.936930315656, 12763.298018608693, 11706.824397633873, 12009.579664711693, 12145.332006739362, 12169.69575760613, 12291.815622765487, 11647.417629118341, 11895.10786691019, 11937.164398492023, 12007.039436845205, 12043.518259269531, 10534.209934255116, 10770.51532681047, 10891.891081089405, 11191.0, 11568.710782230512, 9824.312298139062, 9871.16095131893, 9893.604255829687, 10111.54498047575, 10168.847467288144, 9582.377145485521, 9799.363041402286, 9804.36271642341, 9861.95069024679, 9959.850602241117], "value": 7017.627595415005, "unit": "score"}], "name": "dromaeo_css", "value": 7664.787624137884, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 110.0, 116.0, 117.0, 116.0, 133.0, 116.0, 115.0, 116.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 118.0, 140.0, 159.0, 122.0, 169.0, 172.0, 124.0, 167.0, 122.0], "value": 144.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 195.0, 193.0, 194.0, 192.0, 222.0, 180.0, 264.0, 273.0, 204.0], "value": 211.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 81.0, 77.0, 141.0, 75.0, 55.0, 58.0, 70.0, 127.0, 83.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 80.0, 125.0, 76.0, 79.0, 98.0, 145.0, 80.0, 82.0, 141.0], "value": 99.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 107.0, 88.0, 83.0, 81.0, 116.0, 76.0, 76.0], "value": 89.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 126.0, 95.0, 95.0, 93.0, 95.0, 95.0, 121.0, 148.0, 97.0], "value": 111.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 150.0, 90.0, 92.0, 89.0, 120.0, 142.0, 91.0, 90.0, 91.0], "value": 110.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 61.0, 63.0, 68.0, 56.0, 57.0, 62.0, 77.0, 60.0, 57.0], "value": 61.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 65.0, 72.0, 67.0, 70.0, 67.0, 68.0, 65.0, 65.0], "value": 66.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 81.0, 79.0, 79.0, 81.0, 110.0, 106.0, 107.0, 111.0], "value": 91.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 131.0, 147.0, 149.0, 129.0, 127.0, 145.0, 145.0, 152.0, 166.0], "value": 141.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 158.0, 221.0, 200.0, 164.0, 167.0, 159.0, 166.0, 165.0, 165.0], "value": 178.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 97.0, 61.0, 80.0, 101.0, 100.0, 72.0, 65.0, 98.0, 97.0], "value": 82.6, "unit": "score"}], "name": "kraken", "value": 1590.2000000000003, "alertThreshold": 2.0}]} 04:50:56 INFO - Return code: 0 04:50:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:50:56 ERROR - # TBPL SUCCESS # 04:50:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:50:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:50:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:50:56 INFO - 2016-07-25 04:50:56.406 screenresolution[1684:10391] starting screenresolution argv=screenresolution get 04:50:56 INFO - 2016-07-25 04:50:56.408 screenresolution[1684:10391] Display 0: 1600x1200x32@60 04:50:56 INFO - 2016-07-25 04:50:56.414 screenresolution[1685:10394] starting screenresolution argv=screenresolution list 04:50:56 INFO - Available Modes on Display 0 04:50:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:50:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:50:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:50:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:50:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:50:56 INFO - Intel Iris: 04:50:56 INFO - Chipset Model: Intel Iris 04:50:56 INFO - Type: GPU 04:50:56 INFO - Bus: Built-In 04:50:56 INFO - VRAM (Dynamic, Max): 1536 MB 04:50:56 INFO - Vendor: Intel (0x8086) 04:50:56 INFO - Device ID: 0x0a2e 04:50:56 INFO - Revision ID: 0x0009 04:50:56 INFO - Displays: 04:50:56 INFO - Display: 04:50:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:50:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:50:56 INFO - Display Serial Number: 1600x1200 60 04:50:56 INFO - Main Display: Yes 04:50:56 INFO - Mirror: Off 04:50:56 INFO - Online: Yes 04:50:56 INFO - Rotation: Supported 04:50:56 INFO - Return code: 0 04:50:56 INFO - Running post-action listener: _resource_record_post_action 04:50:56 INFO - Running post-run listener: _resource_record_post_run 04:50:57 INFO - Total resource usage - Wall time: 1193s; CPU: 53.0%; Read bytes: 38139392; Write bytes: 495427584; Read time: 402; Write time: 1812 04:50:57 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 194855424; Write bytes: 196036096; Read time: 17627; Write time: 417 04:50:57 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 36320768; Write bytes: 298543616; Read time: 375; Write time: 1391 04:50:57 INFO - Running post-run listener: _upload_blobber_files 04:50:57 INFO - Blob upload gear active. 04:50:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:50:57 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 04:50:57 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:50:57 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:50:58 INFO - (blobuploader) - INFO - Open directory for files ... 04:50:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:50:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:50:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:58 INFO - (blobuploader) - INFO - Done attempting. 04:50:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:50:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:50:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:59 INFO - (blobuploader) - INFO - Done attempting. 04:50:59 INFO - (blobuploader) - INFO - Iteration through files over. 04:50:59 INFO - Return code: 0 04:50:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:50:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:50:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5372127c384dd7d91edfd01f2f7cd38ad1512ab069261b2785bc105ca53a1943d3eba297d75a53a66cc5d98e880b8e9470a15bd5ad24da75d155a4121775baf6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4ce3b6c98f3d665dfb751c39934b85dd84b3a8484becfc5c29dc9d27a78fdc312b75b58ddb092ee29d84aeee612126cf574378f4e8e92f864526bea77e36129a"} 04:50:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:50:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:50:59 INFO - Contents: 04:50:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5372127c384dd7d91edfd01f2f7cd38ad1512ab069261b2785bc105ca53a1943d3eba297d75a53a66cc5d98e880b8e9470a15bd5ad24da75d155a4121775baf6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4ce3b6c98f3d665dfb751c39934b85dd84b3a8484becfc5c29dc9d27a78fdc312b75b58ddb092ee29d84aeee612126cf574378f4e8e92f864526bea77e36129a"} 04:50:59 INFO - Running post-run listener: copy_logs_to_upload_dir 04:50:59 INFO - Copying logs to upload dir... 04:50:59 INFO - mkdir: /builds/slave/test/build/upload/logs 04:50:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.794488 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-07-25 04:51:00.023009) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 04:51:00.027052) ========= 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.5qZgxSJFPS/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.OBTHWuiNLS/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5372127c384dd7d91edfd01f2f7cd38ad1512ab069261b2785bc105ca53a1943d3eba297d75a53a66cc5d98e880b8e9470a15bd5ad24da75d155a4121775baf6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4ce3b6c98f3d665dfb751c39934b85dd84b3a8484becfc5c29dc9d27a78fdc312b75b58ddb092ee29d84aeee612126cf574378f4e8e92f864526bea77e36129a"} build_url:https://queue.taskcluster.net/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011241 build_url: 'https://queue.taskcluster.net/v1/task/QOoOeqNgTpm2k9NUgO_UKg/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5372127c384dd7d91edfd01f2f7cd38ad1512ab069261b2785bc105ca53a1943d3eba297d75a53a66cc5d98e880b8e9470a15bd5ad24da75d155a4121775baf6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4ce3b6c98f3d665dfb751c39934b85dd84b3a8484becfc5c29dc9d27a78fdc312b75b58ddb092ee29d84aeee612126cf574378f4e8e92f864526bea77e36129a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 04:51:00.063031) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:51:00.063414) ========= 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.5qZgxSJFPS/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.OBTHWuiNLS/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.005270 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:51:00.112510) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 04:51:00.112863) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 04:51:00.113240) ========= ========= Total master_lag: 0.19 =========