builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0020 starttime: 1467102376.29 results: success (0) buildid: 20160627053125 builduid: 91d531b5383642ed8679b7f776c5ef70 revision: 8664ad5c52883cb66cb9b46879eb062fb6600756 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:16.290743) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:16.291344) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:16.291794) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:16.316878) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:16.317279) ========= 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.q0XjjJinlU/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.uyO91KP3PQ/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-28 01:26:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.4M=0.001s 2016-06-28 01:26:16 (12.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134644 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:16.473945) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:16.477317) ========= 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.q0XjjJinlU/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.uyO91KP3PQ/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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.028889 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:16.529456) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-28 01:26:16.529947) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 8664ad5c52883cb66cb9b46879eb062fb6600756 --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 8664ad5c52883cb66cb9b46879eb062fb6600756 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.q0XjjJinlU/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.uyO91KP3PQ/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-28 01:26:16,605 truncating revision to first 12 chars 2016-06-28 01:26:16,605 Setting DEBUG logging. 2016-06-28 01:26:16,606 attempt 1/10 2016-06-28 01:26:16,606 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/8664ad5c5288?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-28 01:26:18,034 unpacking tar archive at: mozilla-beta-8664ad5c5288/testing/mozharness/ program finished with exit code 0 elapsedTime=1.686299 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-28 01:26:18.238014) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:18.238373) ========= script_repo_revision: 8664ad5c52883cb66cb9b46879eb062fb6600756 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:18.238773) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:18.239037) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:18.257132) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 55 secs) (at 2016-06-28 01:26:18.257541) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.q0XjjJinlU/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.uyO91KP3PQ/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:26:18 INFO - MultiFileLogger online at 20160628 01:26:18 in /builds/slave/test 01:26:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 01:26:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:26:18 INFO - {'append_to_log': False, 01:26:18 INFO - 'base_work_dir': '/builds/slave/test', 01:26:18 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:26:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:26:18 INFO - 'branch': 'Mozilla-Beta', 01:26:18 INFO - 'buildbot_json_path': 'buildprops.json', 01:26:18 INFO - 'config_files': ('talos/mac_config.py',), 01:26:18 INFO - 'default_actions': ('clobber', 01:26:18 INFO - 'read-buildbot-config', 01:26:18 INFO - 'download-and-extract', 01:26:18 INFO - 'populate-webroot', 01:26:18 INFO - 'create-virtualenv', 01:26:18 INFO - 'install', 01:26:18 INFO - 'run-tests'), 01:26:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:26:18 INFO - 'download_minidump_stackwalk': True, 01:26:18 INFO - 'download_symbols': 'ondemand', 01:26:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:26:18 INFO - 'tooltool.py': '/tools/tooltool.py', 01:26:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:26:18 INFO - '/tools/misc-python/virtualenv.py')}, 01:26:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:26:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:26:18 INFO - 'installer_path': 'installer.exe', 01:26:18 INFO - 'log_level': 'info', 01:26:18 INFO - 'log_name': 'talos', 01:26:18 INFO - 'log_to_console': True, 01:26:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:26:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:26:18 INFO - 'opt_config_files': (), 01:26:18 INFO - 'pip_index': False, 01:26:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:26:18 INFO - 'cmd': ('bash', 01:26:18 INFO - '-c', 01:26:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:26:18 INFO - 'enabled': True, 01:26:18 INFO - 'halt_on_failure': False, 01:26:18 INFO - 'name': 'check_screen_resolution'},), 01:26:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:26:18 INFO - 'cmd': ('bash', 01:26:18 INFO - '-c', 01:26:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:26:18 INFO - 'enabled': True, 01:26:18 INFO - 'halt_on_failure': False, 01:26:18 INFO - 'name': 'check_screen_resolution'},), 01:26:18 INFO - 'run_cmd_checks_enabled': True, 01:26:18 INFO - 'sps_profile': False, 01:26:18 INFO - 'sps_profile_interval': 0, 01:26:18 INFO - 'suite': 'dromaeojs', 01:26:18 INFO - 'system_bits': '32', 01:26:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:26:18 INFO - 'title': 't-yosemite-r7-0020', 01:26:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:26:18 INFO - 'use_talos_json': True, 01:26:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:26:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:26:18 INFO - 'work_dir': 'build'} 01:26:18 INFO - ##### 01:26:18 INFO - ##### Running clobber step. 01:26:18 INFO - ##### 01:26:18 INFO - Running pre-action listener: _resource_record_pre_action 01:26:18 INFO - Running main action method: clobber 01:26:18 INFO - rmtree: /builds/slave/test/build 01:26:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:26:20 INFO - Running post-action listener: _resource_record_post_action 01:26:20 INFO - ##### 01:26:20 INFO - ##### Running read-buildbot-config step. 01:26:20 INFO - ##### 01:26:20 INFO - Running pre-action listener: _resource_record_pre_action 01:26:20 INFO - Running main action method: read_buildbot_config 01:26:20 INFO - Using buildbot properties: 01:26:20 INFO - { 01:26:20 INFO - "project": "", 01:26:20 INFO - "product": "firefox", 01:26:20 INFO - "script_repo_revision": "production", 01:26:20 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 01:26:20 INFO - "repository": "", 01:26:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 01:26:20 INFO - "buildid": "20160627053125", 01:26:20 INFO - "pgo_build": "False", 01:26:20 INFO - "basedir": "/builds/slave/test", 01:26:20 INFO - "buildnumber": 17, 01:26:20 INFO - "slavename": "t-yosemite-r7-0020", 01:26:20 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 01:26:20 INFO - "platform": "yosemite_r7", 01:26:20 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 01:26:20 INFO - "branch": "mozilla-beta", 01:26:20 INFO - "repo_path": "releases/mozilla-beta", 01:26:20 INFO - "revision": "8664ad5c52883cb66cb9b46879eb062fb6600756", 01:26:20 INFO - "stage_platform": "macosx64", 01:26:20 INFO - "builduid": "91d531b5383642ed8679b7f776c5ef70", 01:26:20 INFO - "slavebuilddir": "test" 01:26:20 INFO - } 01:26:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 01:26:20 INFO - Running post-action listener: _resource_record_post_action 01:26:20 INFO - ##### 01:26:20 INFO - ##### Running download-and-extract step. 01:26:20 INFO - ##### 01:26:20 INFO - Running pre-action listener: _resource_record_pre_action 01:26:20 INFO - Running main action method: download_and_extract 01:26:20 INFO - mkdir: /builds/slave/test/build/tests 01:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:20 INFO - https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:26:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 01:26:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 01:26:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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 01:26:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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 01:26:21 INFO - Downloaded 1165 bytes. 01:26:21 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 01:26:21 INFO - Using the following test package requirements: 01:26:21 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 01:26:21 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:21 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 01:26:21 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:21 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 01:26:21 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 01:26:21 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:21 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 01:26:21 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 01:26:21 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:21 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 01:26:21 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:21 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 01:26:21 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:21 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 01:26:21 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:21 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 01:26:21 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 01:26:21 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')]} 01:26:21 INFO - https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:26:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:26:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:26:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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 01:26:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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 01:26:22 INFO - Downloaded 18344127 bytes. 01:26:22 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'] 01:26:22 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 01:26:26 INFO - Return code: 0 01:26:26 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 01:26:26 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')]} 01:26:26 INFO - https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:26:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:26:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:26:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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 01:26:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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 01:26:27 INFO - Downloaded 18344127 bytes. 01:26:27 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'] 01:26:27 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 01:26:30 INFO - Return code: 0 01:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:30 INFO - https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:26:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 01:26:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 01:26:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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 01:26:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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 01:26:31 INFO - Downloaded 10840145 bytes. 01:26:31 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'] 01:26:31 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 01:26:31 INFO - Return code: 0 01:26: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')]} 01:26:31 INFO - https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 01:26:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:26:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:26:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 01:26:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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 01:26:34 INFO - Downloaded 86228614 bytes. 01:26:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:26:34 INFO - mkdir: /builds/slave/test/properties 01:26:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:26:34 INFO - Writing to file /builds/slave/test/properties/build_url 01:26:34 INFO - Contents: 01:26:34 INFO - build_url:https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:26:35 INFO - Running post-action listener: _resource_record_post_action 01:26:35 INFO - Running post-action listener: set_extra_try_arguments 01:26:35 INFO - ##### 01:26:35 INFO - ##### Running populate-webroot step. 01:26:35 INFO - ##### 01:26:35 INFO - Running pre-action listener: _resource_record_pre_action 01:26:35 INFO - Running main action method: populate_webroot 01:26:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:26:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:26:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:26:35 INFO - '--tppagecycles', 01:26:35 INFO - '7'], 01:26:35 INFO - 'tests': ['tsvgm']}}, 01:26:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:26:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:26:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:26:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:26:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:26:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:26:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:35 INFO - 'talos_options': ['--disable-e10s'], 01:26:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:26:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:26:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:35 INFO - 'talos_options': ['--disable-e10s'], 01:26:35 INFO - 'tests': ['damp', 'tps']}, 01:26:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:35 INFO - 'tests': ['damp', 'tps']}, 01:26:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:26:35 INFO - 'tests': ['dromaeo_dom']}, 01:26:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:26:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:26:35 INFO - 'tests': ['a11yr', 01:26:35 INFO - 'ts_paint', 01:26:35 INFO - 'tpaint', 01:26:35 INFO - 'sessionrestore', 01:26:35 INFO - 'sessionrestore_no_auto_restore', 01:26:35 INFO - 'tabpaint']}, 01:26:35 INFO - 'other-e10s': {'tests': ['a11yr', 01:26:35 INFO - 'ts_paint', 01:26:35 INFO - 'tpaint', 01:26:35 INFO - 'sessionrestore', 01:26:35 INFO - 'sessionrestore_no_auto_restore', 01:26:35 INFO - 'tabpaint']}, 01:26:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:26:35 INFO - 'ts_paint', 01:26:35 INFO - 'tpaint', 01:26:35 INFO - 'sessionrestore', 01:26:35 INFO - 'sessionrestore_no_auto_restore', 01:26:35 INFO - 'tabpaint']}, 01:26:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:26:35 INFO - 'ts_paint', 01:26:35 INFO - 'tpaint', 01:26:35 INFO - 'sessionrestore', 01:26:35 INFO - 'sessionrestore_no_auto_restore', 01:26:35 INFO - 'tabpaint']}, 01:26:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:26:35 INFO - 'tests': ['a11yr', 01:26:35 INFO - 'ts_paint', 01:26:35 INFO - 'tpaint', 01:26:35 INFO - 'sessionrestore', 01:26:35 INFO - 'sessionrestore_no_auto_restore', 01:26:35 INFO - 'tabpaint']}, 01:26:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:26:35 INFO - 'tests': ['a11yr', 01:26:35 INFO - 'ts_paint', 01:26:35 INFO - 'tpaint', 01:26:35 INFO - 'sessionrestore', 01:26:35 INFO - 'sessionrestore_no_auto_restore', 01:26:35 INFO - 'tabpaint']}, 01:26:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:26:35 INFO - 'tests': ['tsvgx', 01:26:35 INFO - 'tsvgr_opacity', 01:26:35 INFO - 'tart', 01:26:35 INFO - 'tscrollx', 01:26:35 INFO - 'cart']}, 01:26:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:26:35 INFO - 'tsvgr_opacity', 01:26:35 INFO - 'tart', 01:26:35 INFO - 'tscrollx', 01:26:35 INFO - 'cart']}, 01:26:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:35 INFO - 'talos_options': ['--disable-e10s'], 01:26:35 INFO - 'tests': ['tp5o']}, 01:26:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:35 INFO - 'tests': ['tp5o']}, 01:26:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:35 INFO - 'talos_options': ['--disable-e10s', 01:26:35 INFO - '--xperf_path', 01:26:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:26:35 INFO - 'tests': ['tp5n']}, 01:26:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:35 INFO - 'talos_options': ['--xperf_path', 01:26:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:26:35 INFO - 'tests': ['tp5n']}}, 01:26:35 INFO - 'talos.zip': {'path': '', 01:26:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:26:35 INFO - Running post-action listener: _resource_record_post_action 01:26:35 INFO - ##### 01:26:35 INFO - ##### Running create-virtualenv step. 01:26:35 INFO - ##### 01:26:35 INFO - Running pre-action listener: _resource_record_pre_action 01:26:35 INFO - Running main action method: create_virtualenv 01:26:35 INFO - Creating virtualenv /builds/slave/test/build/venv 01:26:35 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 01:26:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:26:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:26:35 INFO - Using real prefix '/tools/python27' 01:26:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:26:36 INFO - Installing distribute.............................................................................................................................................................................................done. 01:26:39 INFO - Installing pip.................done. 01:26:39 INFO - Return code: 0 01:26:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:26: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')]} 01:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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')]} 01:26:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b283a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b293420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d394ade10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b122e00>, '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 0x10af8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b11db20>, '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.q0XjjJinlU/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.uyO91KP3PQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:26: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', 'pip>=1.5'] in /builds/slave/test/build 01:26: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 pip>=1.5 01:26:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q0XjjJinlU/Render', 01:26:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:39 INFO - 'HOME': '/Users/cltbld', 01:26:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:39 INFO - 'LOGNAME': 'cltbld', 01:26:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:39 INFO - 'MOZ_NO_REMOTE': '1', 01:26:39 INFO - 'NO_EM_RESTART': '1', 01:26:39 INFO - 'PAGER': '/bin/cat', 01:26:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:39 INFO - 'PWD': '/builds/slave/test', 01:26:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:39 INFO - 'SHELL': '/bin/bash', 01:26:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uyO91KP3PQ/Listeners', 01:26:39 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:26:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:39 INFO - 'USER': 'cltbld', 01:26:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:39 INFO - 'XPC_FLAGS': '0x0', 01:26:39 INFO - 'XPC_SERVICE_NAME': '0', 01:26:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:39 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 01:26:39 INFO - Cleaning up... 01:26:39 INFO - Return code: 0 01:26:39 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:26: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')]} 01:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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')]} 01:26:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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', '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 0x10b283a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b293420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d394ade10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b122e00>, '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 0x10af8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b11db20>, '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.q0XjjJinlU/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.uyO91KP3PQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:26: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', 'psutil>=0.7.1'] in /builds/slave/test/build 01:26: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 psutil>=0.7.1 01:26:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q0XjjJinlU/Render', 01:26:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:39 INFO - 'HOME': '/Users/cltbld', 01:26:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:39 INFO - 'LOGNAME': 'cltbld', 01:26:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:39 INFO - 'MOZ_NO_REMOTE': '1', 01:26:39 INFO - 'NO_EM_RESTART': '1', 01:26:39 INFO - 'PAGER': '/bin/cat', 01:26:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:39 INFO - 'PWD': '/builds/slave/test', 01:26:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:39 INFO - 'SHELL': '/bin/bash', 01:26:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uyO91KP3PQ/Listeners', 01:26:39 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:26:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:39 INFO - 'USER': 'cltbld', 01:26:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:39 INFO - 'XPC_FLAGS': '0x0', 01:26:39 INFO - 'XPC_SERVICE_NAME': '0', 01:26:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:39 INFO - Downloading/unpacking psutil>=0.7.1 01:26: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 01:26: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 01:26: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 01:26: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 01:26: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 01:26: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 01:26:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:26:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:26:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:26:42 INFO - Installing collected packages: psutil 01:26:42 INFO - Running setup.py install for psutil 01:26:42 INFO - building 'psutil._psutil_osx' extension 01:26:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 01:26:43 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 01:26:43 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 01:26:43 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 01:26:43 INFO - building 'psutil._psutil_posix' extension 01:26:43 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 01:26:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:26:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:26:43 INFO - ^ 01:26:43 INFO - 1 warning generated. 01:26:43 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 01:26:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:26:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:26:44 INFO - Successfully installed psutil 01:26:44 INFO - Cleaning up... 01:26:44 INFO - Return code: 0 01:26:44 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01:26: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')]} 01:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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')]} 01:26:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:44 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 0x10b283a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b293420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d394ade10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b122e00>, '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 0x10af8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b11db20>, '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.q0XjjJinlU/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.uyO91KP3PQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:26:44 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 01:26:44 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 01:26:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q0XjjJinlU/Render', 01:26:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:44 INFO - 'HOME': '/Users/cltbld', 01:26:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:44 INFO - 'LOGNAME': 'cltbld', 01:26:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:44 INFO - 'MOZ_NO_REMOTE': '1', 01:26:44 INFO - 'NO_EM_RESTART': '1', 01:26:44 INFO - 'PAGER': '/bin/cat', 01:26:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:44 INFO - 'PWD': '/builds/slave/test', 01:26:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:44 INFO - 'SHELL': '/bin/bash', 01:26:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uyO91KP3PQ/Listeners', 01:26:44 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:26:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:44 INFO - 'USER': 'cltbld', 01:26:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:44 INFO - 'XPC_FLAGS': '0x0', 01:26:44 INFO - 'XPC_SERVICE_NAME': '0', 01:26:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:46 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:26:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:26:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:26:46 INFO - Installing collected packages: mozsystemmonitor 01:26:46 INFO - Running setup.py install for mozsystemmonitor 01:26:46 INFO - Successfully installed mozsystemmonitor 01:26:46 INFO - Cleaning up... 01:26:47 INFO - Return code: 0 01:26:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:47 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 0x10b283a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b293420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d394ade10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b122e00>, '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 0x10af8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b11db20>, '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.q0XjjJinlU/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.uyO91KP3PQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:26:47 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 01:26:47 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 01:26:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q0XjjJinlU/Render', 01:26:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:47 INFO - 'HOME': '/Users/cltbld', 01:26:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:47 INFO - 'LOGNAME': 'cltbld', 01:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:47 INFO - 'MOZ_NO_REMOTE': '1', 01:26:47 INFO - 'NO_EM_RESTART': '1', 01:26:47 INFO - 'PAGER': '/bin/cat', 01:26:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:47 INFO - 'PWD': '/builds/slave/test', 01:26:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:47 INFO - 'SHELL': '/bin/bash', 01:26:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uyO91KP3PQ/Listeners', 01:26:47 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:26:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:47 INFO - 'USER': 'cltbld', 01:26:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:47 INFO - 'XPC_FLAGS': '0x0', 01:26:47 INFO - 'XPC_SERVICE_NAME': '0', 01:26:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:47 INFO - Downloading/unpacking blobuploader==1.2.4 01:26:47 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 01:26:47 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 01:26:47 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 01:26:47 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 01:26:47 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 01:26:47 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 01:26:49 INFO - Downloading blobuploader-1.2.4.tar.gz 01:26:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:26:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:26:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:50 INFO - Downloading docopt-0.6.1.tar.gz 01:26:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:26:51 INFO - Installing collected packages: blobuploader, requests, docopt 01:26:51 INFO - Running setup.py install for blobuploader 01:26:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:26:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:26:51 INFO - Running setup.py install for requests 01:26:51 INFO - Running setup.py install for docopt 01:26:51 INFO - Successfully installed blobuploader requests docopt 01:26:51 INFO - Cleaning up... 01:26:51 INFO - Return code: 0 01:26:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:51 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 0x10b283a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b293420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d394ade10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b122e00>, '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 0x10af8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b11db20>, '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.q0XjjJinlU/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.uyO91KP3PQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:26:51 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 01:26:51 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 01:26:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q0XjjJinlU/Render', 01:26:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:51 INFO - 'HOME': '/Users/cltbld', 01:26:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:51 INFO - 'LOGNAME': 'cltbld', 01:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:51 INFO - 'MOZ_NO_REMOTE': '1', 01:26:51 INFO - 'NO_EM_RESTART': '1', 01:26:52 INFO - 'PAGER': '/bin/cat', 01:26:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:52 INFO - 'PWD': '/builds/slave/test', 01:26:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:52 INFO - 'SHELL': '/bin/bash', 01:26:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uyO91KP3PQ/Listeners', 01:26:52 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:26:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:52 INFO - 'USER': 'cltbld', 01:26:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:52 INFO - 'XPC_FLAGS': '0x0', 01:26:52 INFO - 'XPC_SERVICE_NAME': '0', 01:26:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:26:52 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-FzjxZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:26:52 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-KhHVxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:26:52 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-jPyUeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:26:52 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-TPLvNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:26:52 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-g_jPMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:26:52 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-kbraA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:26:52 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-dNocXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:26:52 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-goIbbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:26:53 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-CW2CCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:26:53 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-nBSbiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:26:53 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-Mv5WuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:26:53 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-ftlR_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:26:53 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-Fhcjkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:26:53 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-0ieWqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:26:53 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-J8t8nY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:26:53 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-OEG2Lq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:26:53 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-rU_HXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:26:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:26:54 INFO - Running setup.py install for manifestparser 01:26:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:26:54 INFO - Running setup.py install for mozcrash 01:26:54 INFO - Running setup.py install for mozdebug 01:26:54 INFO - Running setup.py install for mozdevice 01:26:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:26:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:26:54 INFO - Running setup.py install for mozfile 01:26:54 INFO - Running setup.py install for mozhttpd 01:26:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:26:54 INFO - Running setup.py install for mozinfo 01:26:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:26:55 INFO - Running setup.py install for mozInstall 01:26:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:26:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:26:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:26:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:26:55 INFO - Running setup.py install for mozleak 01:26:55 INFO - Running setup.py install for mozlog 01:26:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:26:55 INFO - Running setup.py install for moznetwork 01:26:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:26:55 INFO - Running setup.py install for mozprocess 01:26:55 INFO - Running setup.py install for mozprofile 01:26:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:26:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:26:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:26:55 INFO - Running setup.py install for mozrunner 01:26:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:26:56 INFO - Running setup.py install for mozscreenshot 01:26:56 INFO - Running setup.py install for moztest 01:26:56 INFO - Running setup.py install for mozversion 01:26:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:26:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:26:56 INFO - Cleaning up... 01:26:56 INFO - Return code: 0 01:26:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:26:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:56 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 0x10b283a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b293420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d394ade10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b122e00>, '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 0x10af8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b11db20>, '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.q0XjjJinlU/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.uyO91KP3PQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:26:56 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 01:26:56 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 01:26:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q0XjjJinlU/Render', 01:26:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:56 INFO - 'HOME': '/Users/cltbld', 01:26:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:56 INFO - 'LOGNAME': 'cltbld', 01:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:56 INFO - 'MOZ_NO_REMOTE': '1', 01:26:56 INFO - 'NO_EM_RESTART': '1', 01:26:56 INFO - 'PAGER': '/bin/cat', 01:26:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:56 INFO - 'PWD': '/builds/slave/test', 01:26:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:56 INFO - 'SHELL': '/bin/bash', 01:26:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uyO91KP3PQ/Listeners', 01:26:56 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:26:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:56 INFO - 'USER': 'cltbld', 01:26:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:56 INFO - 'XPC_FLAGS': '0x0', 01:26:56 INFO - 'XPC_SERVICE_NAME': '0', 01:26:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:26:56 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-P7WaHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:26:56 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)) 01:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:26:56 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-VeBOtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:26:57 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)) 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:26:57 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-1TZ2mN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:26:57 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)) 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:26:57 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-KDH8Qk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:26:57 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)) 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:26:57 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-9zOKdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:26:57 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)) 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:26:57 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-jNLX7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:26:57 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)) 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:26:57 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-vq9nt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:26:57 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)) 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:26:57 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-h8MpyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:26:57 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)) 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:26:57 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-uEAkmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:26:57 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)) 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:26:57 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-w_C4kW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:26:57 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)) 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:26:57 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-bve08c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:26:57 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)) 01:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:26:58 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-1TaCDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:26:58 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)) 01:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:26:58 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-RckvYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:26:58 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)) 01:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:26:58 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-cpKTjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:26:58 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)) 01:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:26:58 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-Y02cYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:26:58 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)) 01:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:26:58 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-GURUHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:26:58 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)) 01:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:26:58 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-9X0fBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:26:58 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)) 01:26:58 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)) 01:26:58 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)) 01:26:58 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)) 01:26:58 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)) 01:26:58 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)) 01:26:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:26: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 01:26: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 01:26: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 01:26: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 01:26: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 01:26: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 01:27:01 INFO - Downloading blessings-1.6.tar.gz 01:27:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:27:01 INFO - Installing collected packages: blessings 01:27:01 INFO - Running setup.py install for blessings 01:27:01 INFO - Successfully installed blessings 01:27:01 INFO - Cleaning up... 01:27:01 INFO - Return code: 0 01:27:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:27:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:27:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:27:01 INFO - Reading from file tmpfile_stdout 01:27:01 INFO - Current package versions: 01:27:01 INFO - blessings == 1.6 01:27:01 INFO - blobuploader == 1.2.4 01:27:01 INFO - docopt == 0.6.1 01:27:01 INFO - manifestparser == 1.1 01:27:01 INFO - mozInstall == 1.12 01:27:01 INFO - mozcrash == 0.17 01:27:01 INFO - mozdebug == 0.1 01:27:01 INFO - mozdevice == 0.48 01:27:01 INFO - mozfile == 1.2 01:27:01 INFO - mozhttpd == 0.7 01:27:01 INFO - mozinfo == 0.9 01:27:01 INFO - mozleak == 0.1 01:27:01 INFO - mozlog == 3.1 01:27:01 INFO - moznetwork == 0.27 01:27:01 INFO - mozprocess == 0.22 01:27:01 INFO - mozprofile == 0.28 01:27:01 INFO - mozrunner == 6.11 01:27:01 INFO - mozscreenshot == 0.1 01:27:01 INFO - mozsystemmonitor == 0.0 01:27:01 INFO - moztest == 0.7 01:27:01 INFO - mozversion == 1.4 01:27:01 INFO - psutil == 3.1.1 01:27:01 INFO - requests == 1.2.3 01:27:01 INFO - wsgiref == 0.1.2 01:27:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:01 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 0x10b283a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b293420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d394ade10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b122e00>, '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 0x10af8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b11db20>, '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.q0XjjJinlU/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.uyO91KP3PQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:27:01 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 01:27:01 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 01:27:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q0XjjJinlU/Render', 01:27:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:01 INFO - 'HOME': '/Users/cltbld', 01:27:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:01 INFO - 'LOGNAME': 'cltbld', 01:27:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:01 INFO - 'MOZ_NO_REMOTE': '1', 01:27:01 INFO - 'NO_EM_RESTART': '1', 01:27:01 INFO - 'PAGER': '/bin/cat', 01:27:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:01 INFO - 'PWD': '/builds/slave/test', 01:27:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:01 INFO - 'SHELL': '/bin/bash', 01:27:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uyO91KP3PQ/Listeners', 01:27:01 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:27:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:01 INFO - 'USER': 'cltbld', 01:27:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:01 INFO - 'XPC_FLAGS': '0x0', 01:27:01 INFO - 'XPC_SERVICE_NAME': '0', 01:27:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:02 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)) 01:27:02 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)) 01:27:02 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)) 01:27:02 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)) 01:27:02 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)) 01:27:02 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)) 01:27:02 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)) 01:27:02 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)) 01:27:02 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)) 01:27:02 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)) 01:27:02 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)) 01:27:02 INFO - Cleaning up... 01:27:02 INFO - Return code: 0 01:27:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:27:02 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')]} 01:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:02 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')]} 01:27:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:02 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 0x10b283a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b293420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d394ade10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b122e00>, '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 0x10af8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b11db20>, '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.q0XjjJinlU/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.uyO91KP3PQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:27:02 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 01:27:02 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 01:27:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q0XjjJinlU/Render', 01:27:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:02 INFO - 'HOME': '/Users/cltbld', 01:27:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:02 INFO - 'LOGNAME': 'cltbld', 01:27:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:02 INFO - 'MOZ_NO_REMOTE': '1', 01:27:02 INFO - 'NO_EM_RESTART': '1', 01:27:02 INFO - 'PAGER': '/bin/cat', 01:27:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:02 INFO - 'PWD': '/builds/slave/test', 01:27:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:02 INFO - 'SHELL': '/bin/bash', 01:27:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uyO91KP3PQ/Listeners', 01:27:02 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:27:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:02 INFO - 'USER': 'cltbld', 01:27:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:02 INFO - 'XPC_FLAGS': '0x0', 01:27:02 INFO - 'XPC_SERVICE_NAME': '0', 01:27:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:02 INFO - Downloading/unpacking jsonschema 01:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:27:04 INFO - Downloading/unpacking functools32 (from jsonschema) 01:27:04 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 01:27:04 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 01:27:04 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 01:27:04 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 01:27:04 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 01:27:04 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 01:27:05 INFO - Downloading functools32-3.2.3-2.tar.gz 01:27:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:27:05 INFO - warning: no files found matching '*.txt' 01:27:05 INFO - no previously-included directories found matching 'build' 01:27:05 INFO - no previously-included directories found matching 'dist' 01:27:05 INFO - no previously-included directories found matching '.git*' 01:27:05 INFO - Installing collected packages: jsonschema, functools32 01:27:05 INFO - Running setup.py install for functools32 01:27:05 INFO - warning: no files found matching '*.txt' 01:27:05 INFO - no previously-included directories found matching 'build' 01:27:05 INFO - no previously-included directories found matching 'dist' 01:27:05 INFO - no previously-included directories found matching '.git*' 01:27:05 INFO - Successfully installed jsonschema functools32 01:27:05 INFO - Cleaning up... 01:27:05 INFO - Return code: 0 01:27:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:27:05 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')]} 01:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:05 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')]} 01:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:05 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 0x10b283a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b293420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d394ade10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b122e00>, '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 0x10af8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b11db20>, '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.q0XjjJinlU/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.uyO91KP3PQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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 01:27:05 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 01:27:05 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 01:27:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q0XjjJinlU/Render', 01:27:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:05 INFO - 'HOME': '/Users/cltbld', 01:27:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:05 INFO - 'LOGNAME': 'cltbld', 01:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:05 INFO - 'MOZ_NO_REMOTE': '1', 01:27:05 INFO - 'NO_EM_RESTART': '1', 01:27:05 INFO - 'PAGER': '/bin/cat', 01:27:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:05 INFO - 'PWD': '/builds/slave/test', 01:27:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:05 INFO - 'SHELL': '/bin/bash', 01:27:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uyO91KP3PQ/Listeners', 01:27:05 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:27:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:05 INFO - 'USER': 'cltbld', 01:27:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:05 INFO - 'XPC_FLAGS': '0x0', 01:27:05 INFO - 'XPC_SERVICE_NAME': '0', 01:27:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:05 INFO - Downloading/unpacking flake8 01:27:05 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 01:27:05 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 01:27:05 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 01:27:05 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 01:27:05 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 01:27:05 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 01:27:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:27:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:27:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:27:09 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 01:27:09 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 01:27:09 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 01:27:09 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 01:27:09 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 01:27:09 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 01:27:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:27:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:27:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:27:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:27:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:27:09 INFO - no previously-included directories found matching 'docs/_build' 01:27:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:27:09 INFO - Running setup.py install for pep8 01:27:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:27:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:27:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:27:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:27:09 INFO - no previously-included directories found matching 'docs/_build' 01:27:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:27:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:27:09 INFO - Cleaning up... 01:27:10 INFO - Return code: 0 01:27:10 INFO - Running post-action listener: _resource_record_post_action 01:27:10 INFO - Running post-action listener: _start_resource_monitoring 01:27:10 INFO - Starting resource monitoring. 01:27:10 INFO - ##### 01:27:10 INFO - ##### Running install step. 01:27:10 INFO - ##### 01:27:10 INFO - Running pre-action listener: _resource_record_pre_action 01:27:10 INFO - Running main action method: install 01:27:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:27:10 INFO - Reading from file tmpfile_stdout 01:27:10 INFO - Detecting whether we're running mozinstall >=1.0... 01:27:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:27:10 INFO - Reading from file tmpfile_stdout 01:27:10 INFO - Output received: 01:27:10 INFO - Usage: mozinstall [options] installer 01:27:10 INFO - Options: 01:27:10 INFO - -h, --help show this help message and exit 01:27:10 INFO - -d DEST, --destination=DEST 01:27:10 INFO - Directory to install application into. [default: 01:27:10 INFO - "/builds/slave/test"] 01:27:10 INFO - --app=APP Application being installed. [default: firefox] 01:27:10 INFO - mkdir: /builds/slave/test/build/application 01:27:10 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'] 01:27:10 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 01:27:32 INFO - Reading from file tmpfile_stdout 01:27:32 INFO - Output received: 01:27:32 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 01:27:32 INFO - Running post-action listener: _resource_record_post_action 01:27:32 INFO - ##### 01:27:32 INFO - ##### Running run-tests step. 01:27:32 INFO - ##### 01:27:32 INFO - Running pre-action listener: _resource_record_pre_action 01:27:32 INFO - Running main action method: run_tests 01:27:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:27:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:27:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:27:32 INFO - 2016-06-28 01:27:32.593 screenresolution[1635:7380] starting screenresolution argv=screenresolution get 01:27:32 INFO - 2016-06-28 01:27:32.596 screenresolution[1635:7380] Display 0: 1600x1200x32@60 01:27:32 INFO - 2016-06-28 01:27:32.601 screenresolution[1636:7383] starting screenresolution argv=screenresolution list 01:27:32 INFO - Available Modes on Display 0 01:27:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:27:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:27:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:27:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:27:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:27:32 INFO - Intel Iris: 01:27:32 INFO - Chipset Model: Intel Iris 01:27:32 INFO - Type: GPU 01:27:32 INFO - Bus: Built-In 01:27:32 INFO - VRAM (Dynamic, Max): 1536 MB 01:27:32 INFO - Vendor: Intel (0x8086) 01:27:32 INFO - Device ID: 0x0a2e 01:27:32 INFO - Revision ID: 0x0009 01:27:32 INFO - Displays: 01:27:32 INFO - Display: 01:27:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:27:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:27:32 INFO - Display Serial Number: 1600x1200 60 01:27:32 INFO - Main Display: Yes 01:27:32 INFO - Mirror: Off 01:27:32 INFO - Online: Yes 01:27:32 INFO - Rotation: Supported 01:27:32 INFO - Return code: 0 01:27:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:27:32 INFO - Python 2.7.3 01:27:32 INFO - Return code: 0 01:27:32 INFO - grabbing minidump binary from tooltool 01:27:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:32 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 0x10b122e00>, '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 0x10af8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b11db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:27:32 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 01:27:32 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 01:27:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:27:32 INFO - Return code: 0 01:27:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:27:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:27:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:27:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:27:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:27:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:27:34 INFO - Return code: 0 01:27:34 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', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:27:34 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 --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0020 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:27:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q0XjjJinlU/Render', 01:27:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:34 INFO - 'HOME': '/Users/cltbld', 01:27:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:34 INFO - 'LOGNAME': 'cltbld', 01:27:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:27:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:27:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:34 INFO - 'MOZ_NO_REMOTE': '1', 01:27:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:27:34 INFO - 'NO_EM_RESTART': '1', 01:27:34 INFO - 'PAGER': '/bin/cat', 01:27:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:34 INFO - 'PWD': '/builds/slave/test', 01:27:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:27:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:34 INFO - 'SHELL': '/bin/bash', 01:27:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uyO91KP3PQ/Listeners', 01:27:34 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 01:27:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:34 INFO - 'USER': 'cltbld', 01:27:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:34 INFO - 'XPC_FLAGS': '0x0', 01:27:34 INFO - 'XPC_SERVICE_NAME': '0', 01:27:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:27:34 INFO - mozversion application_buildid: 20160627053125 01:27:34 INFO - mozversion application_changeset: 8664ad5c52883cb66cb9b46879eb062fb6600756 01:27:34 INFO - mozversion application_display_name: Firefox 01:27:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:27:34 INFO - mozversion application_name: Firefox 01:27:34 INFO - mozversion application_remotingname: firefox 01:27:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:27:34 INFO - mozversion application_vendor: Mozilla 01:27:34 INFO - mozversion application_version: 48.0 01:27:34 INFO - mozversion platform_buildid: 20160627053125 01:27:34 INFO - mozversion platform_changeset: 8664ad5c52883cb66cb9b46879eb062fb6600756 01:27:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:27:34 INFO - mozversion platform_version: 48.0 01:27:34 INFO - using testdate: 1467102454 01:27:34 INFO - actual date: 1467102454 01:27:34 INFO - starting webserver on 'localhost:49277' 01:27:34 INFO - SUITE-START | Running 2 tests 01:27:34 INFO - TEST-START | dromaeo_css 01:27:34 INFO - operating with platform_type : mac_ 01:27:34 INFO - Initialising browser for dromaeo_css test... 01:27:34 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpF1143s/profile http://localhost:49277/getInfo.html) 01:27:36 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 01:27:36 INFO - PROCESS | 1654 | colorDepth:24 01:27:36 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 01:27:36 INFO - PROCESS | 1654 | __metrics 01:27:36 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:27:36 ERROR - PROCESS | 1654 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:27:37 INFO - TEST-INFO | 1654: exit 0 01:27:37 INFO - Browser initialized. 01:27:37 INFO - Running cycle 1/1 for dromaeo_css test... 01:27:37 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpF1143s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:27:49 INFO - PROCESS | 1657 | RSS: Main: 161435648 01:27:49 INFO - PROCESS | 1657 | 01:28:40 INFO - PROCESS | 1657 | 1467102520443 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] 01:30:50 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 01:30:51 INFO - PROCESS | 1657 | RSS: Main: 256638976 01:30:51 INFO - PROCESS | 1657 | 01:33:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 01:33:55 INFO - PROCESS | 1657 | RSS: Main: 303710208 01:33:55 INFO - PROCESS | 1657 | 01:36:57 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 01:36:57 INFO - PROCESS | 1657 | RSS: Main: 314097664 01:36:57 INFO - PROCESS | 1657 | 01:39:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 01:40:00 INFO - PROCESS | 1657 | RSS: Main: 303910912 01:40:00 INFO - PROCESS | 1657 | 01:43:03 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 01:43:04 INFO - PROCESS | 1657 | RSS: Main: 268029952 01:43:04 INFO - PROCESS | 1657 | 01:46:06 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 01:46:06 INFO - PROCESS | 1657 | RSS: Main: 316760064 01:46:06 INFO - PROCESS | 1657 | 01:46:06 INFO - PROCESS | 1657 | __start_tp_report 01:46:06 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 01:46:06 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 01:46:06 INFO - PROCESS | 1657 | |i|pagename|runs| 01:46:06 INFO - PROCESS | 1657 | |0;dojo.html;2819.22471320387;3601.3697602919483;3652.817359132043;3657.9085522861924;3721.9069523261915;5963.880722385549;6034.668093254869;6042.57701960863;6047.153398524205;6053.303870054945;6035.366286539905;6068.150458935742;6089.756409743605;6090.482309003729;6101.78643747469;5345.7594408251525;5369.838904832861;5454.945450545503;5474.069408200596;5522.22688823563;5434.266374039514;5502.477264659851;5503.752331145088;5510.448955104477;5514.91727624084;5409.594280428964;5444.210589464526;5485.286912701344;5490.945090549103;5491.835244942658;5508.283923090013;5511.9724401378135;5526.723663816793;5531.1426728857095;5541.0580201365865;5339.41266460689;5358.4373640767935;5383.838484845461;5431.456854314537;5488.17677348397;5833.329167145747;5864.1203819427;5913.970430147821;5921.052631578969;5946.643201407929;6342.302346741854;6343.524235682342;6369.076483909854;6382.680865956683;6394.616323020634;6303.810885673437;6429.774957876499;6468.288488266287;6470.870582588369;6475.834349817031;6179.5674302798825;6186.56694031418;6201.6899155042065;6209.782657607007;6229.595076320115;6588.176477940257;6594.637294948824;6598.043283723833;6608.537402381787;6611.041398997215;6244.12582238487;6253.7185826637915;6349.365063493614;6349.52378571609;6356.713947872357;6412.166418365676;6418.454431373292;6427.485801135898;6431.839204019937;6439.259485159173;6279.748810047547;6327.272363678236;6331.303556608769;6333.2400111985935;6373.139626150411;6280.152179455807;6289.905651415233;6332.081848131995;6338.271098823602;6357.713902874381;5087.465816089331;5123.923141152885;5127.487251274917;5136.434992150935;5138.537531622172;4584.724916505021;4604.7927843247135;4634.86095417138;4648.83729069481;4659.068186362673;6026.216591843032;6042.305134909454;6093.939060609426;6122.081687746873;6139.369480960641;6396.2964073951825;6404.903926441107;6409.134766806515;6412.935870641234;6423.839404014937;5079.796808127708;6112.847178820565;6143;6156.722947467375;6157.415045570664;5454.10007348783;5606;5643.80246691372;5649.519790817744;5662.8301150965535;6619.9466294237245;6619.973904044785;6640.435562977104;6663.833404164935;6665.50008749336;6269.435750782452;6398.488120950406;6408.166938297992;6422.743090276336;6424.518161138027;6537.934620653733;6613.371729685672;6634.336566343327;6655.767048153291;6655.866882662222;6320;6328.968355158195;6330.683465826598;6343.238811342669;6371.299157092623;6221.129041934042;6263.78076767311;6293.307736149111;6373.904391434039;6377.968110159605;6153.84615384619;6268.341824108586;6296.937030629635;6322.33615470369;6327.493800496041;6275.37246275387;6304;6306.432421082125;6323.399277068671;6343;6128.075379514812;6200;6214.285357183981;6264.968675156595;6299.527535434952;6165.753369865155;6220.098085777447;6275.748970041147;6283.308836027942;6307.810765677037;6280.403361680816;6290.24517057956;6297.244330680348;6310.400511951358;6315.08431277453 01:46:06 INFO - PROCESS | 1657 | |1;ext.html;535.3442033508954;553.3056014701549;580.6864293281628;598.7215944585766;613.3651670521011;32932.50601240983;33157.17107072324;33413.99758007264;33491.99524014277;33532.83233583832;41593.584064159346;42384.152316953645;42442.57557424425;42490.57509424913;42515;9746.83038035435;9953.75115622108;9957.751056223617;9976.152027077671;9986.300958932878;9240;9760.219182465422;9943.403395796238;12036.277823330585;12410.751784964295;11968;12067.336296503689;12075.818862717022;12094.213876098025;12100.939495302555;11998.580049698263;12097.032237420985;12108.818367724403;12112.878871211307;12142.635720928387;11819.704507387298;11941.104417168746;11946.342951137683;11959.701007474796;11976.520939162423;40321.79839100815;40814.38778418326;42638.72083837459;42768;42822;3501.859925602973;3632.0738211755834;3828.221589978093;3906.8437262509465;4008.8797336079965;29216.8539157305;29346.119616411543;29427.411451770844;29549.556756648668;29665.851670741726;2792.3577577157366;2871.5405535114282;2875.0656036788127;2909.476294267031;2945.5434407666844;398790;399964;400322;400532;400681;56688.43311566914;63862;66057.33942660608;66135.00797488041;66272.33727662661;66049.66975165189;66334;66536.33463665302;66594;66630.00054999179;18230.36193733213;18433.893966362062;18434.446966590756;18449.815501845078;18495.630087398178;11161.944190279159;14196.574102776727;14311.284435778169;14320.997530172788;14732.116073035651;15006.67396553536;16899;16990.150492475328;17065.976041437552;17069.57326066858;5565.443455654483;5819.82540523785;6021.608595441282;6433.581817181962;6465.741370345228;924.8242833861544;981.40134517944;988.9258305627106;1040.9902394677151;1153.581095252827;29085.418291634473;29278.560821587693;29322.706772932423;29466.705332946825;29795.85102074519;1032.4528000159928;1152.8674202466657;1212.902967762577;1228.3980849383738;1247.7442124364484;15395.923020384826;15457.458988935334;15537.378504859678;15596.844031559765;15652.693418301258;636373;642816;645039;645606.7719661371;646268;63047;64900.675496622214;65015;65015.67492162509;65167.674161628885;1415.568251683226;1438.439008786574;1467.5890750589851;1559.119097709813;1559.3294883200333;29228.41543169082;29790.95731649382;29907.85046074843;30011;30703.692963070083;44211;49192;49216.26175607297;49237.753811230716;49299;372668;375330;375898.1205093957;376908;658307;27939.720602793714;28439;28642.85678571594;28720.85639571789;28738.71261287444;43279.78360108179;45059.77470112628;45118;45627.859303517675;46508.53491465042;37243.81378093092;38381.57317705192;38477.6152238474;38548.036299091626;39258;33938.15154621154;38373.04067398337;38467.2306553873;38577.80711096426;38680.6131938677;38660.420093698056;39032.80483597677;39101.217975640895;39132;39393.2121357577;29672;29836.552451713676;32146.51780223345;32379.704811807263;32466;22974.53473080139;23096.653550197098;24121.638175427033;24971.875140624907;26059.348516287246 01:46:06 INFO - PROCESS | 1657 | |2;jquery.html;412.48027485368425;412.93805929110647;413.99793001035;415.27741729390857;422.9957700422997;19137.808621913777;20572.588548229047;21294.254701085465;21295.787042129574;21382.89308553459;21416.143354265812;21537.784622153773;21541.030653620586;21551.784482155173;21558.784412155874;5478.643888147258;6081.665508397036;6085.634861908277;6086.239220097488;6107.114468402079;5881.823545293648;6054.576179667425;6061.605995610294;6061.757529698807;6062.514998800086;5804.796832110877;6130.44825965663;6136.693165341738;6140.723667434976;6145.846353841167;5781.479666830005;6012.128241404994;6091.939080609226;6112.174856394375;6123.020316749321;4501.932471012938;4512.4133862597655;5214.791408343662;5264.315638966948;5266.789328426858;21272;26512.818461784518;26602.866985664947;26821.329466763487;26865.731342686715;2222.388843068153;2301.3096071178747;2384.225126833786;2481.367251350912;2660.0689758584535;8984.730458086202;14325.35535900887;14707.1616047994;15073.397064117531;15106.697866042727;1777.6622441736029;1798.6672465593906;1804;1821.4444594398703;1827.3786912449698;123082;130314.6968530312;139966;143521.28239358737;152055;20900.65589505672;21380.037898294613;21431.142754289653;21472.355829325144;21595;36154.27691446209;36261;36913.63086369156;36924.26151477009;37005.8898233048;10832.025117739442;11769.823452648217;11856.585019524447;11874.287542747463;11884.524619015143;9574.664886728931;9686.418814871131;9771.951140244253;9865.950670246602;9935.850962235572;9821.705348839547;9830.508474576243;9961.651342202988;10013.599456021677;10037.996200379903;3341.777717817624;3867.903302417462;3950.525936887592;3979.2041591681777;4113.8971525712095;953.0387426646421;981.1169947047572;1013.6452241715341;1082.4533610526635;1100.312304809494;17017.31930722782;20089.296874609612;20480.487987800127;20503.077361518768;20689.27587534429;922.2161163011386;931.3573634192386;1001.3591301567003;1006.104566935428;1059.3643813711865;10914.454277286262;11056.613018544313;11071.889281107247;11112.499937502831;11121.499532521057;565044;571131;628077;630592;644745;128255;134980;135469;136540;140142;1045.9790804184026;1058.5130839813771;1062.2404980439023;1071.132382769959;1074.6346242277507;18088.914665120134;18189.727154092965;18231.544211394823;18473.81526184721;19194.424167275003;51264.74367628138;53070.73464632652;54116.45883541272;54750.63995936127;55102.44897551133;78044;78634.4273114546;80233.59883200546;80804.59597701974;81509;8351.665933362841;8433.83132337362;8474.788130296793;8556.229939305493;8837.072107428627;12275.815862762245;12585.119041666996;12740.362981851056;12908.8063679043;13014.609561713163;10581.682549523526;10730.248882578457;11021.779564408507;11452.885471145182;11460.255083419552;10690.786184276427;11053.28153670009;11118.944405278246;11346.886531134583;11379.203455758348;9745.707628771148;10977.890221097687;11321.377324247245;11543.229624890133;11697.23967942081;8073.717419890276;8161;8279.710210142615;8401.411901166859;8558.229759321692;8222.917770822454;9342.392744471588;10078.647247346307;10948.233623646269;10981.890181098086 01:46:06 INFO - PROCESS | 1657 | |3;mootools.html;1517.1579773225856;1632.4041724770461;1648.9835101648987;1914.578792665613;2014.7582290125179;8255.51400747866;8914.331425143117;9073.500957447337;9079.77300567486;9116.772080697978;8681.73954781356;8959.552022398886;8969.865452018224;8977.326700497457;9018.68434604789;2531.4683916377585;2549.7577730115704;2550.668413106303;2558.4243545202276;2561.923142305734;2484.5776218042975;2561.679790026247;2561.8847151878117;2565.5638541447906;2576.0597381955545;2530.304166354258;2548.324693956103;2563.3079068651455;2567.4608332250154;2567.6533667954773;2512.4975004998983;2528.785053270465;2534.3664083979006;2568.8201825872197;2573.7812285955715;2513.3339664988794;2538.5430622487943;2549.2989427907287;2549.757773011561;2598.3244356467453;8842.33682473817;9030.232430243437;9145.268378529701;9187.586558604879;9220.308476864262;5588.580856435784;5672.574556908248;5722.713864306768;5725.570582206309;5741.856453588652;3615.385384484644;3695.1131728384994;3739.775613463201;3740.812959352049;3761.736678432511;913.488446469979;1030.8247597908298;1043.0508237503834;1050.3277902142636;1083.3445765311951;12439.25364478125;12612.81080783789;12631.431585578672;12636.989040876708;12648.304343261118;4772.475027746945;4879.878003049881;4911.189653707098;4940.629452791055;4969.428515720739;9872;9875.160611347972;9886.505674716236;9890.258230632731;9899.455529945779;5663.915041274384;5678.261825962599;5679.829605111853;5684.8010319638615;5690.3456102548735;4722.338872557844;4839.395075615548;5113.795448182105;5164.948350516447;5252.369715634072;4014.879553613396;4019.738716983445;4102.2821006324375;4128.938065929014;4144.91710165795;1252.5052604221282;1259.8299229603886;1262.444524409257;1272.1858010873048;1280.7438512297392;920.447731361178;943.3208090175063;947.1428357336621;978.5889926230858;993.5777294649745;6087.543434242361;6176.474999625082;6233.844153896098;6266.279377871512;6330.303666596671;480.37393423262506;482.8696252011937;490.03218643180145;490.3282502970953;495.38324785642106;3980.3233450312937;3989.8204580793354;4013.1572369802084;4099.795010249535;4114.382842573638;10957.12343012557;11316.773664526829;11474.082073434105;11521.53913843437;11561.42192890352;11206.15953803468;11237.94381028106;11291.266067705737;11297.26567773092;11312.717182070513;8271.8345633087;8500.44747091437;8594.5702714864;8607.053224145338;8608.569571521399;6046.939530604814;6047.516198704006;6207.410296021871;6221;6228.937710622836;7765.650545725343;10010.04904534068;10090.747731306776;10105.090541851268;10130.544125514221;9969.900300996896;10409.635662751767;10416.375017498975;10520.10579100785;10546.894531054591;7154.856902862018;7223.783286501413;7811.921880781347;7895.486793358414;8054.556999364857;8612.267957223456;8650.005249396148;8676.522791246542;8683.869741954002;8683.913160868311;7572.432067595063;7711.575863327571;7747.186545412876;7750.496217745828;7836.216378362346;7475.962620186864;7502.737404190827;7563.848723025399;7623.313901748869;7645.006149200791;7197.676104575202;7225.494215404871;7252.202257751748;7257.600831954293;7269.927300727137;4908.460069392436;4936.802527898987;5126;5126.307948426915;5167.844964651066;5074.517920797518;5080.339555857715;5096;5199.506046925536;5329.573634109184 01:46:06 INFO - PROCESS | 1657 | |4;prototype.html;331.9867205311788;335.30926291838813;342.9984445419376;345.1268291223206;345.7458767805661;6744.662766861662;6745.730170793175;6755.054292399056;6772.764987554107;6779;6702.564333318331;6722;6731.831704207398;6751.662416879149;6762.188537375522;4842.031593681269;4958.479359667237;4959.429665588467;4960.950390496103;4965.1062808694605;4881.560659540641;4961.3798275215595;4978.228374601942;5014.674046187004;5033.6090357080275;4850.126977144113;4967.602591792648;4973.303737476756;4974.900501989976;4975.004999000196;4958.429780575244;4983.601311895058;4983.72589507577;4989.476105008958;4994.07609592223;4106.07613287011;4152.190322887038;4267.335739061768;4310.094880075188;4311.310190369557;5407.242985981966;6313.842153946142;6506;6524.804255872284;6537.444317233041;5936.1095835625;5938.61399009063;5943.286805583314;5944.643321400687;5951.107333899907;6145.661988590626;6803.4897382696;7157.2127066022695;7161.964190179068;7166.247544007856;4463.821447142111;4765.2375619900995;4768.904621907543;4780.354652121989;4782.306565547976;7524.510906791041;7569.962150189324;7626.237376262385;7633.809154771119;7652.120006199302;4727.692699974491;4742.66801323904;4746.003339298717;4747.430308362949;4752.596029337476;4096.549379568245;4807.894184337622;4836.419629644395;4838.177509823338;4851.417829860439;5757.539396848242;5759.963206622764;5761.740721667535;5775.220345253337;5777.104548794943;6966.651667416609;7223.494355395177;7237.312455316736;7240.746573869889;7249.492535522463;1451.6008097772985;1622.7261599644223;1654.5450001249512;1675.9664806703797;1684.6209602839508;1056.5034433816095;1056.5472475346398;1075.3655343347461;1081.086481922774;1082.6914329416236;5488;6643.335666433415;6750.831229219309;6808.080909077211;6829.419499342513;7577.734779282699;7599.696012159562;7605.087389513294;7610.086789585174;7622.69509219625;6843.828904277433;6857.622830744257;6859.862802744017;6861.725530978805;6865.485088618374;3993.480847489866;4020.7386519876113;4021.075152714892;4025;4045.5752146024834;7652.885206721904;7940.483868548526;7942.841143177104;7985.560794156378;8061.6372263248295;7835.177306382861;7839.686412543549;7851.411144164211;7860.528368297806;7894.921050789419;7950.204979502119;7952.284294413531;7955.164707705608;7958.403119765925;7970.760877173578;7316.1952185258515;7553.131389890013;7560.4329675273475;7572.39420846342;7572.697092116254;5993.340732519507;7335;7365.116186057493;7422.443316751157;7428.851422971618;7859.607019649109;7892;7938.6030698466;7966.16355282687;7986.241307075818;3906.9218615628097;4747.667663263538;4757;4757.738324392191;4767.880802979953;7555.017847679766;7570.848583028199;7576.28025337592;7576.810579735551;7586;6971.058907047686;7240.927590724236;7294.562326260441;7295.890561641686;7301.926980730124;7228.4578656599915;7271.454640901848;7282.669545745311;7290.234525374759;7321.450891183076;7063.117110361205;7122.180949190702;7144.3570078693165;7149.571025738473;7159.964200178966;7048.3304086113785;7256.2018177999325;7265.854682906418;7283.599402032939;7312.232215617283;6597.208334999831;6892.965535172292;7142.178649455172;7173.820654483679;7188.388986936169 01:46:06 INFO - PROCESS | 1657 | |5;yui.html;727.5380133615155;758.2531206761339;776.4758787818221;778.2373274191292;811.691557208261;15388.846111538882;15592.298346574404;15753.291101900413;15761.842381576194;15781.13203773792;14297.142171469719;14345.067570607904;14373.20947347898;14389;14392.208428536427;784.5410434895564;797.0913158998746;934.411320867854;945.135201290818;947.1712251779693;962.7689354554926;965.9855102173473;966.246327864267;967.6129548180713;970.5972021611022;1017.8473229015669;1020.0513522424143;1020.9897901020969;1022.7392015036155;1028.2545154762774;820.122468958215;998.4059484606647;1006.9345492542961;1012.5595366015807;1031.5718976624692;757.4508481351031;758.9943325094217;759.965801538932;760.8630446519625;765.6592816196795;15619.453319133889;15668.529944101696;15693.979891307028;15701.371945122182;15710.764338534931;12706.55527056558;12739.044571657163;12872.357922121928;12965.222086674736;13047.217166970013;10146.137578305852;10167;10266.897331026743;10385.16918646514;10423;3510.2102027043966;3578.6958108560802;3602.7297952653394;3606.747527673064;3679;85686.28627427478;86312.56843715742;86420;86580;86953.695694564;9683.176929961031;9688.176504997013;9783;9927.30508864387;10145.898541014643;23083.884580577327;23109;23111.306660800205;23144;23192.884035579715;7101.82245443868;7213.711451541984;7317.37802286801;7321;7323.194448610649;4156.06488540076;4197.370394440797;4226.556211597738;4321.611055005064;4334.111507141029;3763.6612704856693;3798.525184351956;3826.0626146594263;3834.7315687902137;3837.6354246346555;590.6101972697999;660.7588230295908;671.8310140355843;681.6591704147926;696.926732831444;473.9928901066487;522.2557855056605;604.9485793707585;634.5241069198102;644.7904431059924;7999.960000200078;8325.212028527005;8348.248657620843;8464.238218560358;8713.564321783886;575.8301301116181;579.6811753535538;585.8271809816115;614.5114633866087;634.8063840528683;3794.6395092466173;3900;3939.1333906540517;3966.8809935701397;4003.919921601552;94773.10453790886;95269;95608.52195738976;96760;96911;79159;79556;80488.19511804925;81098;81122;67512;67750.66124669345;68875.3112468869;69092.654536727;69639.65180174066;7043.683034263573;8320.70877519284;8584.184502472255;8638.395312328328;8672.956635217037;47629;47663.76168119137;47756.52243477521;50243.74878125732;51404.48595513997;61709;62882;63773;64696.67651661712;71971.64014180105;11061.557537698402;11376.694440989926;11606.187566870238;11620.709482262673;11627.59303424376;12018.399080046138;12229.14395992272;12500.124991250888;12545.49818007306;12723.745525089262;11556.653300400665;11587.304761714324;11690.76618467643;11911.583094591648;12169.2698438094;11509.712257193638;11701.414929253333;11717.238379505305;11722.069482629342;11762.94118529402;11209.55161793519;11487.885121148682;11675.591354302558;12111.939440302742;12189.75620487603;8584.442011269248;9689.563969621526;9796.608135674493;10172.237082219011;10174.492496473695;9815.852762208715;9910.405375677483;9959.302848800804;9959.551820167964;10124.754102442732 01:46:06 INFO - PROCESS | 1657 | __end_tp_report 01:46:06 INFO - PROCESS | 1657 | __start_cc_report 01:46:06 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1357 01:46:06 INFO - PROCESS | 1657 | __end_cc_report 01:46:06 INFO - PROCESS | 1657 | __startTimestamp1467103566971__endTimestamp 01:46:06 INFO - PROCESS | 1657 | 01:46:06 INFO - PROCESS | 1657 | ------- Summary: start ------- 01:46:06 INFO - PROCESS | 1657 | Number of tests: 6 01:46:06 INFO - PROCESS | 1657 | 01:46:06 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5976.12 Median:6244.13 stddev:649.10 (10.4%) stddev-sans-first:601.99 01:46:06 INFO - PROCESS | 1657 | Values: 2819.2 3601.4 3652.8 3657.9 3721.9 5963.9 6034.7 6042.6 6047.2 6053.3 6035.4 6068.2 6089.8 6090.5 6101.8 5345.8 5369.8 5454.9 5474.1 5522.2 5434.3 5502.5 5503.8 5510.4 5514.9 5409.6 5444.2 5485.3 5490.9 5491.8 5508.3 5512.0 5526.7 5531.1 5541.1 5339.4 5358.4 5383.8 5431.5 5488.2 5833.3 5864.1 5914.0 5921.1 5946.6 6342.3 6343.5 6369.1 6382.7 6394.6 6303.8 6429.8 6468.3 6470.9 6475.8 6179.6 6186.6 6201.7 6209.8 6229.6 6588.2 6594.6 6598.0 6608.5 6611.0 6244.1 6253.7 6349.4 6349.5 6356.7 6412.2 6418.5 6427.5 6431.8 6439.3 6279.7 6327.3 6331.3 6333.2 6373.1 6280.2 6289.9 6332.1 6338.3 6357.7 5087.5 5123.9 5127.5 5136.4 5138.5 4584.7 4604.8 4634.9 4648.8 4659.1 6026.2 6042.3 6093.9 6122.1 6139.4 6396.3 6404.9 6409.1 6412.9 6423.8 5079.8 6112.8 6143.0 6156.7 6157.4 5454.1 5606.0 5643.8 5649.5 5662.8 6619.9 6620.0 6640.4 6663.8 6665.5 6269.4 6398.5 6408.2 6422.7 6424.5 6537.9 6613.4 6634.3 6655.8 6655.9 6320.0 6329.0 6330.7 6343.2 6371.3 6221.1 6263.8 6293.3 6373.9 6378.0 6153.8 6268.3 6296.9 6322.3 6327.5 6275.4 6304.0 6306.4 6323.4 6343.0 6128.1 6200.0 6214.3 6265.0 6299.5 6165.8 6220.1 6275.7 6283.3 6307.8 6280.4 6290.2 6297.2 6310.4 6315.1 01:46:06 INFO - PROCESS | 1657 | 01:46:06 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:64071.50 Median:3566.97 stddev:135591.29 (3801.3%) stddev-sans-first:135887.74 01:46:06 INFO - PROCESS | 1657 | Values: 535.3 553.3 580.7 598.7 613.4 32932.5 33157.2 33414.0 33492.0 33532.8 41593.6 42384.2 42442.6 42490.6 42515.0 9746.8 9953.8 9957.8 9976.2 9986.3 9240.0 9760.2 9943.4 12036.3 12410.8 11968.0 12067.3 12075.8 12094.2 12100.9 11998.6 12097.0 12108.8 12112.9 12142.6 11819.7 11941.1 11946.3 11959.7 11976.5 40321.8 40814.4 42638.7 42768.0 42822.0 3501.9 3632.1 3828.2 3906.8 4008.9 29216.9 29346.1 29427.4 29549.6 29665.9 2792.4 2871.5 2875.1 2909.5 2945.5 398790.0 399964.0 400322.0 400532.0 400681.0 56688.4 63862.0 66057.3 66135.0 66272.3 66049.7 66334.0 66536.3 66594.0 66630.0 18230.4 18433.9 18434.4 18449.8 18495.6 11161.9 14196.6 14311.3 14321.0 14732.1 15006.7 16899.0 16990.2 17066.0 17069.6 5565.4 5819.8 6021.6 6433.6 6465.7 924.8 981.4 988.9 1041.0 1153.6 29085.4 29278.6 29322.7 29466.7 29795.9 1032.5 1152.9 1212.9 1228.4 1247.7 15395.9 15457.5 15537.4 15596.8 15652.7 636373.0 642816.0 645039.0 645606.8 646268.0 63047.0 64900.7 65015.0 65015.7 65167.7 1415.6 1438.4 1467.6 1559.1 1559.3 29228.4 29791.0 29907.9 30011.0 30703.7 44211.0 49192.0 49216.3 49237.8 49299.0 372668.0 375330.0 375898.1 376908.0 658307.0 27939.7 28439.0 28642.9 28720.9 28738.7 43279.8 45059.8 45118.0 45627.9 46508.5 37243.8 38381.6 38477.6 38548.0 39258.0 33938.2 38373.0 38467.2 38577.8 38680.6 38660.4 39032.8 39101.2 39132.0 39393.2 29672.0 29836.6 32146.5 32379.7 32466.0 22974.5 23096.7 24121.6 24971.9 26059.3 01:46:06 INFO - PROCESS | 1657 | 01:46:06 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:37435.10 Median:2261.85 stddev:102006.89 (4509.9%) stddev-sans-first:102255.16 01:46:06 INFO - PROCESS | 1657 | Values: 412.5 412.9 414.0 415.3 423.0 19137.8 20572.6 21294.3 21295.8 21382.9 21416.1 21537.8 21541.0 21551.8 21558.8 5478.6 6081.7 6085.6 6086.2 6107.1 5881.8 6054.6 6061.6 6061.8 6062.5 5804.8 6130.4 6136.7 6140.7 6145.8 5781.5 6012.1 6091.9 6112.2 6123.0 4501.9 4512.4 5214.8 5264.3 5266.8 21272.0 26512.8 26602.9 26821.3 26865.7 2222.4 2301.3 2384.2 2481.4 2660.1 8984.7 14325.4 14707.2 15073.4 15106.7 1777.7 1798.7 1804.0 1821.4 1827.4 123082.0 130314.7 139966.0 143521.3 152055.0 20900.7 21380.0 21431.1 21472.4 21595.0 36154.3 36261.0 36913.6 36924.3 37005.9 10832.0 11769.8 11856.6 11874.3 11884.5 9574.7 9686.4 9772.0 9866.0 9935.9 9821.7 9830.5 9961.7 10013.6 10038.0 3341.8 3867.9 3950.5 3979.2 4113.9 953.0 981.1 1013.6 1082.5 1100.3 17017.3 20089.3 20480.5 20503.1 20689.3 922.2 931.4 1001.4 1006.1 1059.4 10914.5 11056.6 11071.9 11112.5 11121.5 565044.0 571131.0 628077.0 630592.0 644745.0 128255.0 134980.0 135469.0 136540.0 140142.0 1046.0 1058.5 1062.2 1071.1 1074.6 18088.9 18189.7 18231.5 18473.8 19194.4 51264.7 53070.7 54116.5 54750.6 55102.4 78044.0 78634.4 80233.6 80804.6 81509.0 8351.7 8433.8 8474.8 8556.2 8837.1 12275.8 12585.1 12740.4 12908.8 13014.6 10581.7 10730.2 11021.8 11452.9 11460.3 10690.8 11053.3 11118.9 11346.9 11379.2 9745.7 10977.9 11321.4 11543.2 11697.2 8073.7 8161.0 8279.7 8401.4 8558.2 8222.9 9342.4 10078.6 10948.2 10981.9 01:46:06 INFO - PROCESS | 1657 | 01:46:06 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5923.78 Median:5021.97 stddev:3323.32 (66.2%) stddev-sans-first:3316.14 01:46:06 INFO - PROCESS | 1657 | Values: 1517.2 1632.4 1649.0 1914.6 2014.8 8255.5 8914.3 9073.5 9079.8 9116.8 8681.7 8959.6 8969.9 8977.3 9018.7 2531.5 2549.8 2550.7 2558.4 2561.9 2484.6 2561.7 2561.9 2565.6 2576.1 2530.3 2548.3 2563.3 2567.5 2567.7 2512.5 2528.8 2534.4 2568.8 2573.8 2513.3 2538.5 2549.3 2549.8 2598.3 8842.3 9030.2 9145.3 9187.6 9220.3 5588.6 5672.6 5722.7 5725.6 5741.9 3615.4 3695.1 3739.8 3740.8 3761.7 913.5 1030.8 1043.1 1050.3 1083.3 12439.3 12612.8 12631.4 12637.0 12648.3 4772.5 4879.9 4911.2 4940.6 4969.4 9872.0 9875.2 9886.5 9890.3 9899.5 5663.9 5678.3 5679.8 5684.8 5690.3 4722.3 4839.4 5113.8 5164.9 5252.4 4014.9 4019.7 4102.3 4128.9 4144.9 1252.5 1259.8 1262.4 1272.2 1280.7 920.4 943.3 947.1 978.6 993.6 6087.5 6176.5 6233.8 6266.3 6330.3 480.4 482.9 490.0 490.3 495.4 3980.3 3989.8 4013.2 4099.8 4114.4 10957.1 11316.8 11474.1 11521.5 11561.4 11206.2 11237.9 11291.3 11297.3 11312.7 8271.8 8500.4 8594.6 8607.1 8608.6 6046.9 6047.5 6207.4 6221.0 6228.9 7765.7 10010.0 10090.7 10105.1 10130.5 9969.9 10409.6 10416.4 10520.1 10546.9 7154.9 7223.8 7811.9 7895.5 8054.6 8612.3 8650.0 8676.5 8683.9 8683.9 7572.4 7711.6 7747.2 7750.5 7836.2 7476.0 7502.7 7563.8 7623.3 7645.0 7197.7 7225.5 7252.2 7257.6 7269.9 4908.5 4936.8 5126.0 5126.3 5167.8 5074.5 5080.3 5096.0 5199.5 5329.6 01:46:06 INFO - PROCESS | 1657 | 01:46:06 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:5935.05 Median:6751.25 stddev:1921.24 (28.5%) stddev-sans-first:1880.04 01:46:06 INFO - PROCESS | 1657 | Values: 332.0 335.3 343.0 345.1 345.7 6744.7 6745.7 6755.1 6772.8 6779.0 6702.6 6722.0 6731.8 6751.7 6762.2 4842.0 4958.5 4959.4 4961.0 4965.1 4881.6 4961.4 4978.2 5014.7 5033.6 4850.1 4967.6 4973.3 4974.9 4975.0 4958.4 4983.6 4983.7 4989.5 4994.1 4106.1 4152.2 4267.3 4310.1 4311.3 5407.2 6313.8 6506.0 6524.8 6537.4 5936.1 5938.6 5943.3 5944.6 5951.1 6145.7 6803.5 7157.2 7162.0 7166.2 4463.8 4765.2 4768.9 4780.4 4782.3 7524.5 7570.0 7626.2 7633.8 7652.1 4727.7 4742.7 4746.0 4747.4 4752.6 4096.5 4807.9 4836.4 4838.2 4851.4 5757.5 5760.0 5761.7 5775.2 5777.1 6966.7 7223.5 7237.3 7240.7 7249.5 1451.6 1622.7 1654.5 1676.0 1684.6 1056.5 1056.5 1075.4 1081.1 1082.7 5488.0 6643.3 6750.8 6808.1 6829.4 7577.7 7599.7 7605.1 7610.1 7622.7 6843.8 6857.6 6859.9 6861.7 6865.5 3993.5 4020.7 4021.1 4025.0 4045.6 7652.9 7940.5 7942.8 7985.6 8061.6 7835.2 7839.7 7851.4 7860.5 7894.9 7950.2 7952.3 7955.2 7958.4 7970.8 7316.2 7553.1 7560.4 7572.4 7572.7 5993.3 7335.0 7365.1 7422.4 7428.9 7859.6 7892.0 7938.6 7966.2 7986.2 3906.9 4747.7 4757.0 4757.7 4767.9 7555.0 7570.8 7576.3 7576.8 7586.0 6971.1 7240.9 7294.6 7295.9 7301.9 7228.5 7271.5 7282.7 7290.2 7321.5 7063.1 7122.2 7144.4 7149.6 7160.0 7048.3 7256.2 7265.9 7283.6 7312.2 6597.2 6893.0 7142.2 7173.8 7188.4 01:46:06 INFO - PROCESS | 1657 | 01:46:06 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:18673.16 Median:49000.14 stddev:26233.44 (53.5%) stddev-sans-first:26272.42 01:46:06 INFO - PROCESS | 1657 | Values: 727.5 758.3 776.5 778.2 811.7 15388.8 15592.3 15753.3 15761.8 15781.1 14297.1 14345.1 14373.2 14389.0 14392.2 784.5 797.1 934.4 945.1 947.2 962.8 966.0 966.2 967.6 970.6 1017.8 1020.1 1021.0 1022.7 1028.3 820.1 998.4 1006.9 1012.6 1031.6 757.5 759.0 760.0 760.9 765.7 15619.5 15668.5 15694.0 15701.4 15710.8 12706.6 12739.0 12872.4 12965.2 13047.2 10146.1 10167.0 10266.9 10385.2 10423.0 3510.2 3578.7 3602.7 3606.7 3679.0 85686.3 86312.6 86420.0 86580.0 86953.7 9683.2 9688.2 9783.0 9927.3 10145.9 23083.9 23109.0 23111.3 23144.0 23192.9 7101.8 7213.7 7317.4 7321.0 7323.2 4156.1 4197.4 4226.6 4321.6 4334.1 3763.7 3798.5 3826.1 3834.7 3837.6 590.6 660.8 671.8 681.7 696.9 474.0 522.3 604.9 634.5 644.8 8000.0 8325.2 8348.2 8464.2 8713.6 575.8 579.7 585.8 614.5 634.8 3794.6 3900.0 3939.1 3966.9 4003.9 94773.1 95269.0 95608.5 96760.0 96911.0 79159.0 79556.0 80488.2 81098.0 81122.0 67512.0 67750.7 68875.3 69092.7 69639.7 7043.7 8320.7 8584.2 8638.4 8673.0 47629.0 47663.8 47756.5 50243.7 51404.5 61709.0 62882.0 63773.0 64696.7 71971.6 11061.6 11376.7 11606.2 11620.7 11627.6 12018.4 12229.1 12500.1 12545.5 12723.7 11556.7 11587.3 11690.8 11911.6 12169.3 11509.7 11701.4 11717.2 11722.1 11762.9 11209.6 11487.9 11675.6 12111.9 12189.8 8584.4 9689.6 9796.6 10172.2 10174.5 9815.9 9910.4 9959.3 9959.6 10124.8 01:46:06 INFO - PROCESS | 1657 | -------- Summary: end -------- 01:46:06 INFO - PROCESS | 1657 | 01:46:07 INFO - TEST-INFO | 1657: exit 0 01:46:07 INFO - TEST-OK | dromaeo_css | took 1113219ms 01:46:07 INFO - TEST-START | kraken 01:46:07 INFO - operating with platform_type : mac_ 01:46:07 INFO - Initialising browser for kraken test... 01:46:07 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpF5U5ct/profile http://localhost:49277/getInfo.html) 01:46:09 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 01:46:09 INFO - PROCESS | 1668 | colorDepth:24 01:46:09 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 01:46:09 INFO - PROCESS | 1668 | __metrics 01:46:10 INFO - PROCESS | 1668 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:46:10 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:46:10 INFO - TEST-INFO | 1668: exit 0 01:46:10 INFO - Browser initialized. 01:46:10 INFO - Running cycle 1/1 for kraken test... 01:46:10 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpF5U5ct/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:46:22 INFO - PROCESS | 1671 | RSS: Main: 161140736 01:46:22 INFO - PROCESS | 1671 | 01:46:25 INFO - PROCESS | 1671 | 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) 01:46:25 INFO - PROCESS | 1671 | RSS: Main: 223506432 01:46:25 INFO - PROCESS | 1671 | 01:46:28 INFO - PROCESS | 1671 | 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) 01:46:29 INFO - PROCESS | 1671 | RSS: Main: 239312896 01:46:29 INFO - PROCESS | 1671 | 01:46:32 INFO - PROCESS | 1671 | 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) 01:46:32 INFO - PROCESS | 1671 | RSS: Main: 327614464 01:46:32 INFO - PROCESS | 1671 | 01:46:34 INFO - PROCESS | 1671 | 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) 01:46:35 INFO - PROCESS | 1671 | RSS: Main: 231473152 01:46:35 INFO - PROCESS | 1671 | 01:46:37 INFO - PROCESS | 1671 | 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) 01:46:38 INFO - PROCESS | 1671 | RSS: Main: 231714816 01:46:38 INFO - PROCESS | 1671 | 01:46:41 INFO - PROCESS | 1671 | 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) 01:46:41 INFO - PROCESS | 1671 | RSS: Main: 269561856 01:46:41 INFO - PROCESS | 1671 | 01:46:44 INFO - PROCESS | 1671 | 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) 01:46:45 INFO - PROCESS | 1671 | RSS: Main: 270618624 01:46:45 INFO - PROCESS | 1671 | 01:46:47 INFO - PROCESS | 1671 | 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) 01:46:48 INFO - PROCESS | 1671 | RSS: Main: 269914112 01:46:48 INFO - PROCESS | 1671 | 01:46:50 INFO - PROCESS | 1671 | 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) 01:46:50 INFO - PROCESS | 1671 | RSS: Main: 260620288 01:46:50 INFO - PROCESS | 1671 | 01:46:52 INFO - PROCESS | 1671 | 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) 01:46:53 INFO - PROCESS | 1671 | RSS: Main: 266981376 01:46:53 INFO - PROCESS | 1671 | 01:46:55 INFO - PROCESS | 1671 | 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) 01:46:55 INFO - PROCESS | 1671 | RSS: Main: 271003648 01:46:55 INFO - PROCESS | 1671 | 01:46:58 INFO - PROCESS | 1671 | 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) 01:46:59 INFO - PROCESS | 1671 | RSS: Main: 278323200 01:46:59 INFO - PROCESS | 1671 | 01:47:02 INFO - PROCESS | 1671 | 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) 01:47:02 INFO - PROCESS | 1671 | RSS: Main: 274923520 01:47:02 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | 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) 01:47:04 INFO - PROCESS | 1671 | RSS: Main: 335953920 01:47:04 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | __start_tp_report 01:47:04 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 01:47:04 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 01:47:04 INFO - PROCESS | 1671 | |i|pagename|runs| 01:47:04 INFO - PROCESS | 1671 | |0;ai-astar;112;112;114;110;110;110;113;112;109;134 01:47:04 INFO - PROCESS | 1671 | |1;audio-beat-detection;153;121;138;162;119;135;117;149;152;118 01:47:04 INFO - PROCESS | 1671 | |2;audio-dft;186;187;185;181;185;185;185;182;182;183 01:47:04 INFO - PROCESS | 1671 | |3;audio-fft;71;101;159;207;56;54;55;61;131;56 01:47:04 INFO - PROCESS | 1671 | |4;audio-oscillator;91;94;146;88;116;119;134;78;76;126 01:47:04 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;89;86;88;86;88;84;88;86;90;86 01:47:04 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;100;139;94;93;92;92;93;97;93 01:47:04 INFO - PROCESS | 1671 | |7;imaging-desaturate;84;84;84;85;85;86;85;86;84;85 01:47:04 INFO - PROCESS | 1671 | |8;json-parse-financial;58;63;63;62;61;62;63;78;58;56 01:47:04 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;61;63;68;75;73;69;66;66;66;67 01:47:04 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;81;80;79;80;79;80;80;78;102;105 01:47:04 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;127;146;119;123;124;126;123;128;146;142 01:47:04 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;165;162;162;216;216;156;201;216;166;164 01:47:04 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;68;66;67;66;66;66;64;67;67;65 01:47:04 INFO - PROCESS | 1671 | __end_tp_report 01:47:04 INFO - PROCESS | 1671 | __start_cc_report 01:47:04 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1909 01:47:04 INFO - PROCESS | 1671 | __end_cc_report 01:47:04 INFO - PROCESS | 1671 | __startTimestamp1467103624727__endTimestamp 01:47:04 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | ------- Summary: start ------- 01:47:04 INFO - PROCESS | 1671 | Number of tests: 14 01:47:04 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:113.60 Median:112.00 stddev:7.34 (6.6%) stddev-sans-first:7.76 01:47:04 INFO - PROCESS | 1671 | Values: 112.0 112.0 114.0 110.0 110.0 110.0 113.0 112.0 109.0 134.0 01:47:04 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:136.40 Median:143.50 stddev:16.97 (11.8%) stddev-sans-first:16.90 01:47:04 INFO - PROCESS | 1671 | Values: 153.0 121.0 138.0 162.0 119.0 135.0 117.0 149.0 152.0 118.0 01:47:04 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:184.10 Median:185.00 stddev:1.97 (1.1%) stddev-sans-first:1.96 01:47:04 INFO - PROCESS | 1671 | Values: 186.0 187.0 185.0 181.0 185.0 185.0 185.0 182.0 182.0 183.0 01:47:04 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:95.10 Median:55.50 stddev:53.78 (96.9%) stddev-sans-first:56.33 01:47:04 INFO - PROCESS | 1671 | Values: 71.0 101.0 159.0 207.0 56.0 54.0 55.0 61.0 131.0 56.0 01:47:04 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:106.80 Median:77.00 stddev:24.55 (31.9%) stddev-sans-first:25.36 01:47:04 INFO - PROCESS | 1671 | Values: 91.0 94.0 146.0 88.0 116.0 119.0 134.0 78.0 76.0 126.0 01:47:04 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:87.10 Median:88.00 stddev:1.79 (2.0%) stddev-sans-first:1.76 01:47:04 INFO - PROCESS | 1671 | Values: 89.0 86.0 88.0 86.0 88.0 84.0 88.0 86.0 90.0 86.0 01:47:04 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:98.60 Median:93.00 stddev:14.42 (15.5%) stddev-sans-first:15.15 01:47:04 INFO - PROCESS | 1671 | Values: 93.0 100.0 139.0 94.0 93.0 92.0 92.0 93.0 97.0 93.0 01:47:04 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:0.79 (0.9%) stddev-sans-first:0.78 01:47:04 INFO - PROCESS | 1671 | Values: 84.0 84.0 84.0 85.0 85.0 86.0 85.0 86.0 84.0 85.0 01:47:04 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:62.50 stddev:6.02 (9.6%) stddev-sans-first:6.17 01:47:04 INFO - PROCESS | 1671 | Values: 58.0 63.0 63.0 62.0 61.0 62.0 63.0 78.0 58.0 56.0 01:47:04 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:67.50 stddev:4.20 (6.2%) stddev-sans-first:3.76 01:47:04 INFO - PROCESS | 1671 | Values: 61.0 63.0 68.0 75.0 73.0 69.0 66.0 66.0 66.0 67.0 01:47:04 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:80.00 stddev:10.12 (12.7%) stddev-sans-first:10.66 01:47:04 INFO - PROCESS | 1671 | Values: 81.0 80.0 79.0 80.0 79.0 80.0 80.0 78.0 102.0 105.0 01:47:04 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:130.40 Median:127.50 stddev:10.21 (8.0%) stddev-sans-first:10.76 01:47:04 INFO - PROCESS | 1671 | Values: 127.0 146.0 119.0 123.0 124.0 126.0 123.0 128.0 146.0 142.0 01:47:04 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.40 Median:183.50 stddev:26.19 (14.3%) stddev-sans-first:27.01 01:47:04 INFO - PROCESS | 1671 | Values: 165.0 162.0 162.0 216.0 216.0 156.0 201.0 216.0 166.0 164.0 01:47:04 INFO - PROCESS | 1671 | 01:47:04 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.50 stddev:1.14 (1.7%) stddev-sans-first:1.00 01:47:04 INFO - PROCESS | 1671 | Values: 68.0 66.0 67.0 66.0 66.0 66.0 64.0 67.0 67.0 65.0 01:47:04 INFO - PROCESS | 1671 | -------- Summary: end -------- 01:47:04 INFO - PROCESS | 1671 | 01:47:05 INFO - TEST-INFO | 1671: exit 0 01:47:05 INFO - TEST-OK | kraken | took 57966ms 01:47:05 INFO - SUITE-END | took 1171s 01:47:05 INFO - Completed test suite (00:19:31) 01:47:05 INFO - javascript benchmark 01:47:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2819.22471320387, 3601.3697602919483, 3652.817359132043, 3657.9085522861924, 3721.9069523261915, 5963.880722385549, 6034.668093254869, 6042.57701960863, 6047.153398524205, 6053.303870054945, 6035.366286539905, 6068.150458935742, 6089.756409743605, 6090.482309003729, 6101.78643747469, 5345.7594408251525, 5369.838904832861, 5454.945450545503, 5474.069408200596, 5522.22688823563, 5434.266374039514, 5502.477264659851, 5503.752331145088, 5510.448955104477, 5514.91727624084, 5409.594280428964, 5444.210589464526, 5485.286912701344, 5490.945090549103, 5491.835244942658, 5508.283923090013, 5511.9724401378135, 5526.723663816793, 5531.1426728857095, 5541.0580201365865, 5339.41266460689, 5358.4373640767935, 5383.838484845461, 5431.456854314537, 5488.17677348397, 5833.329167145747, 5864.1203819427, 5913.970430147821, 5921.052631578969, 5946.643201407929, 6342.302346741854, 6343.524235682342, 6369.076483909854, 6382.680865956683, 6394.616323020634, 6303.810885673437, 6429.774957876499, 6468.288488266287, 6470.870582588369, 6475.834349817031, 6179.5674302798825, 6186.56694031418, 6201.6899155042065, 6209.782657607007, 6229.595076320115, 6588.176477940257, 6594.637294948824, 6598.043283723833, 6608.537402381787, 6611.041398997215, 6244.12582238487, 6253.7185826637915, 6349.365063493614, 6349.52378571609, 6356.713947872357, 6412.166418365676, 6418.454431373292, 6427.485801135898, 6431.839204019937, 6439.259485159173, 6279.748810047547, 6327.272363678236, 6331.303556608769, 6333.2400111985935, 6373.139626150411, 6280.152179455807, 6289.905651415233, 6332.081848131995, 6338.271098823602, 6357.713902874381, 5087.465816089331, 5123.923141152885, 5127.487251274917, 5136.434992150935, 5138.537531622172, 4584.724916505021, 4604.7927843247135, 4634.86095417138, 4648.83729069481, 4659.068186362673, 6026.216591843032, 6042.305134909454, 6093.939060609426, 6122.081687746873, 6139.369480960641, 6396.2964073951825, 6404.903926441107, 6409.134766806515, 6412.935870641234, 6423.839404014937, 5079.796808127708, 6112.847178820565, 6143.0, 6156.722947467375, 6157.415045570664, 5454.10007348783, 5606.0, 5643.80246691372, 5649.519790817744, 5662.8301150965535, 6619.9466294237245, 6619.973904044785, 6640.435562977104, 6663.833404164935, 6665.50008749336, 6269.435750782452, 6398.488120950406, 6408.166938297992, 6422.743090276336, 6424.518161138027, 6537.934620653733, 6613.371729685672, 6634.336566343327, 6655.767048153291, 6655.866882662222, 6320.0, 6328.968355158195, 6330.683465826598, 6343.238811342669, 6371.299157092623, 6221.129041934042, 6263.78076767311, 6293.307736149111, 6373.904391434039, 6377.968110159605, 6153.84615384619, 6268.341824108586, 6296.937030629635, 6322.33615470369, 6327.493800496041, 6275.37246275387, 6304.0, 6306.432421082125, 6323.399277068671, 6343.0, 6128.075379514812, 6200.0, 6214.285357183981, 6264.968675156595, 6299.527535434952, 6165.753369865155, 6220.098085777447, 6275.748970041147, 6283.308836027942, 6307.810765677037, 6280.403361680816, 6290.24517057956, 6297.244330680348, 6310.400511951358, 6315.08431277453], "value": 5934.876074223842, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [535.3442033508954, 553.3056014701549, 580.6864293281628, 598.7215944585766, 613.3651670521011, 32932.50601240983, 33157.17107072324, 33413.99758007264, 33491.99524014277, 33532.83233583832, 41593.584064159346, 42384.152316953645, 42442.57557424425, 42490.57509424913, 42515.0, 9746.83038035435, 9953.75115622108, 9957.751056223617, 9976.152027077671, 9986.300958932878, 9240.0, 9760.219182465422, 9943.403395796238, 12036.277823330585, 12410.751784964295, 11968.0, 12067.336296503689, 12075.818862717022, 12094.213876098025, 12100.939495302555, 11998.580049698263, 12097.032237420985, 12108.818367724403, 12112.878871211307, 12142.635720928387, 11819.704507387298, 11941.104417168746, 11946.342951137683, 11959.701007474796, 11976.520939162423, 40321.79839100815, 40814.38778418326, 42638.72083837459, 42768.0, 42822.0, 3501.859925602973, 3632.0738211755834, 3828.221589978093, 3906.8437262509465, 4008.8797336079965, 29216.8539157305, 29346.119616411543, 29427.411451770844, 29549.556756648668, 29665.851670741726, 2792.3577577157366, 2871.5405535114282, 2875.0656036788127, 2909.476294267031, 2945.5434407666844, 398790.0, 399964.0, 400322.0, 400532.0, 400681.0, 56688.43311566914, 63862.0, 66057.33942660608, 66135.00797488041, 66272.33727662661, 66049.66975165189, 66334.0, 66536.33463665302, 66594.0, 66630.00054999179, 18230.36193733213, 18433.893966362062, 18434.446966590756, 18449.815501845078, 18495.630087398178, 11161.944190279159, 14196.574102776727, 14311.284435778169, 14320.997530172788, 14732.116073035651, 15006.67396553536, 16899.0, 16990.150492475328, 17065.976041437552, 17069.57326066858, 5565.443455654483, 5819.82540523785, 6021.608595441282, 6433.581817181962, 6465.741370345228, 924.8242833861544, 981.40134517944, 988.9258305627106, 1040.9902394677151, 1153.581095252827, 29085.418291634473, 29278.560821587693, 29322.706772932423, 29466.705332946825, 29795.85102074519, 1032.4528000159928, 1152.8674202466657, 1212.902967762577, 1228.3980849383738, 1247.7442124364484, 15395.923020384826, 15457.458988935334, 15537.378504859678, 15596.844031559765, 15652.693418301258, 636373.0, 642816.0, 645039.0, 645606.7719661371, 646268.0, 63047.0, 64900.675496622214, 65015.0, 65015.67492162509, 65167.674161628885, 1415.568251683226, 1438.439008786574, 1467.5890750589851, 1559.119097709813, 1559.3294883200333, 29228.41543169082, 29790.95731649382, 29907.85046074843, 30011.0, 30703.692963070083, 44211.0, 49192.0, 49216.26175607297, 49237.753811230716, 49299.0, 372668.0, 375330.0, 375898.1205093957, 376908.0, 658307.0, 27939.720602793714, 28439.0, 28642.85678571594, 28720.85639571789, 28738.71261287444, 43279.78360108179, 45059.77470112628, 45118.0, 45627.859303517675, 46508.53491465042, 37243.81378093092, 38381.57317705192, 38477.6152238474, 38548.036299091626, 39258.0, 33938.15154621154, 38373.04067398337, 38467.2306553873, 38577.80711096426, 38680.6131938677, 38660.420093698056, 39032.80483597677, 39101.217975640895, 39132.0, 39393.2121357577, 29672.0, 29836.552451713676, 32146.51780223345, 32379.704811807263, 32466.0, 22974.53473080139, 23096.653550197098, 24121.638175427033, 24971.875140624907, 26059.348516287246], "value": 20213.302269124604, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.48027485368425, 412.93805929110647, 413.99793001035, 415.27741729390857, 422.9957700422997, 19137.808621913777, 20572.588548229047, 21294.254701085465, 21295.787042129574, 21382.89308553459, 21416.143354265812, 21537.784622153773, 21541.030653620586, 21551.784482155173, 21558.784412155874, 5478.643888147258, 6081.665508397036, 6085.634861908277, 6086.239220097488, 6107.114468402079, 5881.823545293648, 6054.576179667425, 6061.605995610294, 6061.757529698807, 6062.514998800086, 5804.796832110877, 6130.44825965663, 6136.693165341738, 6140.723667434976, 6145.846353841167, 5781.479666830005, 6012.128241404994, 6091.939080609226, 6112.174856394375, 6123.020316749321, 4501.932471012938, 4512.4133862597655, 5214.791408343662, 5264.315638966948, 5266.789328426858, 21272.0, 26512.818461784518, 26602.866985664947, 26821.329466763487, 26865.731342686715, 2222.388843068153, 2301.3096071178747, 2384.225126833786, 2481.367251350912, 2660.0689758584535, 8984.730458086202, 14325.35535900887, 14707.1616047994, 15073.397064117531, 15106.697866042727, 1777.6622441736029, 1798.6672465593906, 1804.0, 1821.4444594398703, 1827.3786912449698, 123082.0, 130314.6968530312, 139966.0, 143521.28239358737, 152055.0, 20900.65589505672, 21380.037898294613, 21431.142754289653, 21472.355829325144, 21595.0, 36154.27691446209, 36261.0, 36913.63086369156, 36924.26151477009, 37005.8898233048, 10832.025117739442, 11769.823452648217, 11856.585019524447, 11874.287542747463, 11884.524619015143, 9574.664886728931, 9686.418814871131, 9771.951140244253, 9865.950670246602, 9935.850962235572, 9821.705348839547, 9830.508474576243, 9961.651342202988, 10013.599456021677, 10037.996200379903, 3341.777717817624, 3867.903302417462, 3950.525936887592, 3979.2041591681777, 4113.8971525712095, 953.0387426646421, 981.1169947047572, 1013.6452241715341, 1082.4533610526635, 1100.312304809494, 17017.31930722782, 20089.296874609612, 20480.487987800127, 20503.077361518768, 20689.27587534429, 922.2161163011386, 931.3573634192386, 1001.3591301567003, 1006.104566935428, 1059.3643813711865, 10914.454277286262, 11056.613018544313, 11071.889281107247, 11112.499937502831, 11121.499532521057, 565044.0, 571131.0, 628077.0, 630592.0, 644745.0, 128255.0, 134980.0, 135469.0, 136540.0, 140142.0, 1045.9790804184026, 1058.5130839813771, 1062.2404980439023, 1071.132382769959, 1074.6346242277507, 18088.914665120134, 18189.727154092965, 18231.544211394823, 18473.81526184721, 19194.424167275003, 51264.74367628138, 53070.73464632652, 54116.45883541272, 54750.63995936127, 55102.44897551133, 78044.0, 78634.4273114546, 80233.59883200546, 80804.59597701974, 81509.0, 8351.665933362841, 8433.83132337362, 8474.788130296793, 8556.229939305493, 8837.072107428627, 12275.815862762245, 12585.119041666996, 12740.362981851056, 12908.8063679043, 13014.609561713163, 10581.682549523526, 10730.248882578457, 11021.779564408507, 11452.885471145182, 11460.255083419552, 10690.786184276427, 11053.28153670009, 11118.944405278246, 11346.886531134583, 11379.203455758348, 9745.707628771148, 10977.890221097687, 11321.377324247245, 11543.229624890133, 11697.23967942081, 8073.717419890276, 8161.0, 8279.710210142615, 8401.411901166859, 8558.229759321692, 8222.917770822454, 9342.392744471588, 10078.647247346307, 10948.233623646269, 10981.890181098086], "value": 10799.467505846585, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1517.1579773225856, 1632.4041724770461, 1648.9835101648987, 1914.578792665613, 2014.7582290125179, 8255.51400747866, 8914.331425143117, 9073.500957447337, 9079.77300567486, 9116.772080697978, 8681.73954781356, 8959.552022398886, 8969.865452018224, 8977.326700497457, 9018.68434604789, 2531.4683916377585, 2549.7577730115704, 2550.668413106303, 2558.4243545202276, 2561.923142305734, 2484.5776218042975, 2561.679790026247, 2561.8847151878117, 2565.5638541447906, 2576.0597381955545, 2530.304166354258, 2548.324693956103, 2563.3079068651455, 2567.4608332250154, 2567.6533667954773, 2512.4975004998983, 2528.785053270465, 2534.3664083979006, 2568.8201825872197, 2573.7812285955715, 2513.3339664988794, 2538.5430622487943, 2549.2989427907287, 2549.757773011561, 2598.3244356467453, 8842.33682473817, 9030.232430243437, 9145.268378529701, 9187.586558604879, 9220.308476864262, 5588.580856435784, 5672.574556908248, 5722.713864306768, 5725.570582206309, 5741.856453588652, 3615.385384484644, 3695.1131728384994, 3739.775613463201, 3740.812959352049, 3761.736678432511, 913.488446469979, 1030.8247597908298, 1043.0508237503834, 1050.3277902142636, 1083.3445765311951, 12439.25364478125, 12612.81080783789, 12631.431585578672, 12636.989040876708, 12648.304343261118, 4772.475027746945, 4879.878003049881, 4911.189653707098, 4940.629452791055, 4969.428515720739, 9872.0, 9875.160611347972, 9886.505674716236, 9890.258230632731, 9899.455529945779, 5663.915041274384, 5678.261825962599, 5679.829605111853, 5684.8010319638615, 5690.3456102548735, 4722.338872557844, 4839.395075615548, 5113.795448182105, 5164.948350516447, 5252.369715634072, 4014.879553613396, 4019.738716983445, 4102.2821006324375, 4128.938065929014, 4144.91710165795, 1252.5052604221282, 1259.8299229603886, 1262.444524409257, 1272.1858010873048, 1280.7438512297392, 920.447731361178, 943.3208090175063, 947.1428357336621, 978.5889926230858, 993.5777294649745, 6087.543434242361, 6176.474999625082, 6233.844153896098, 6266.279377871512, 6330.303666596671, 480.37393423262506, 482.8696252011937, 490.03218643180145, 490.3282502970953, 495.38324785642106, 3980.3233450312937, 3989.8204580793354, 4013.1572369802084, 4099.795010249535, 4114.382842573638, 10957.12343012557, 11316.773664526829, 11474.082073434105, 11521.53913843437, 11561.42192890352, 11206.15953803468, 11237.94381028106, 11291.266067705737, 11297.26567773092, 11312.717182070513, 8271.8345633087, 8500.44747091437, 8594.5702714864, 8607.053224145338, 8608.569571521399, 6046.939530604814, 6047.516198704006, 6207.410296021871, 6221.0, 6228.937710622836, 7765.650545725343, 10010.04904534068, 10090.747731306776, 10105.090541851268, 10130.544125514221, 9969.900300996896, 10409.635662751767, 10416.375017498975, 10520.10579100785, 10546.894531054591, 7154.856902862018, 7223.783286501413, 7811.921880781347, 7895.486793358414, 8054.556999364857, 8612.267957223456, 8650.005249396148, 8676.522791246542, 8683.869741954002, 8683.913160868311, 7572.432067595063, 7711.575863327571, 7747.186545412876, 7750.496217745828, 7836.216378362346, 7475.962620186864, 7502.737404190827, 7563.848723025399, 7623.313901748869, 7645.006149200791, 7197.676104575202, 7225.494215404871, 7252.202257751748, 7257.600831954293, 7269.927300727137, 4908.460069392436, 4936.802527898987, 5126.0, 5126.307948426915, 5167.844964651066, 5074.517920797518, 5080.339555857715, 5096.0, 5199.506046925536, 5329.573634109184], "value": 4694.851136379982, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.9867205311788, 335.30926291838813, 342.9984445419376, 345.1268291223206, 345.7458767805661, 6744.662766861662, 6745.730170793175, 6755.054292399056, 6772.764987554107, 6779.0, 6702.564333318331, 6722.0, 6731.831704207398, 6751.662416879149, 6762.188537375522, 4842.031593681269, 4958.479359667237, 4959.429665588467, 4960.950390496103, 4965.1062808694605, 4881.560659540641, 4961.3798275215595, 4978.228374601942, 5014.674046187004, 5033.6090357080275, 4850.126977144113, 4967.602591792648, 4973.303737476756, 4974.900501989976, 4975.004999000196, 4958.429780575244, 4983.601311895058, 4983.72589507577, 4989.476105008958, 4994.07609592223, 4106.07613287011, 4152.190322887038, 4267.335739061768, 4310.094880075188, 4311.310190369557, 5407.242985981966, 6313.842153946142, 6506.0, 6524.804255872284, 6537.444317233041, 5936.1095835625, 5938.61399009063, 5943.286805583314, 5944.643321400687, 5951.107333899907, 6145.661988590626, 6803.4897382696, 7157.2127066022695, 7161.964190179068, 7166.247544007856, 4463.821447142111, 4765.2375619900995, 4768.904621907543, 4780.354652121989, 4782.306565547976, 7524.510906791041, 7569.962150189324, 7626.237376262385, 7633.809154771119, 7652.120006199302, 4727.692699974491, 4742.66801323904, 4746.003339298717, 4747.430308362949, 4752.596029337476, 4096.549379568245, 4807.894184337622, 4836.419629644395, 4838.177509823338, 4851.417829860439, 5757.539396848242, 5759.963206622764, 5761.740721667535, 5775.220345253337, 5777.104548794943, 6966.651667416609, 7223.494355395177, 7237.312455316736, 7240.746573869889, 7249.492535522463, 1451.6008097772985, 1622.7261599644223, 1654.5450001249512, 1675.9664806703797, 1684.6209602839508, 1056.5034433816095, 1056.5472475346398, 1075.3655343347461, 1081.086481922774, 1082.6914329416236, 5488.0, 6643.335666433415, 6750.831229219309, 6808.080909077211, 6829.419499342513, 7577.734779282699, 7599.696012159562, 7605.087389513294, 7610.086789585174, 7622.69509219625, 6843.828904277433, 6857.622830744257, 6859.862802744017, 6861.725530978805, 6865.485088618374, 3993.480847489866, 4020.7386519876113, 4021.075152714892, 4025.0, 4045.5752146024834, 7652.885206721904, 7940.483868548526, 7942.841143177104, 7985.560794156378, 8061.6372263248295, 7835.177306382861, 7839.686412543549, 7851.411144164211, 7860.528368297806, 7894.921050789419, 7950.204979502119, 7952.284294413531, 7955.164707705608, 7958.403119765925, 7970.760877173578, 7316.1952185258515, 7553.131389890013, 7560.4329675273475, 7572.39420846342, 7572.697092116254, 5993.340732519507, 7335.0, 7365.116186057493, 7422.443316751157, 7428.851422971618, 7859.607019649109, 7892.0, 7938.6030698466, 7966.16355282687, 7986.241307075818, 3906.9218615628097, 4747.667663263538, 4757.0, 4757.738324392191, 4767.880802979953, 7555.017847679766, 7570.848583028199, 7576.28025337592, 7576.810579735551, 7586.0, 6971.058907047686, 7240.927590724236, 7294.562326260441, 7295.890561641686, 7301.926980730124, 7228.4578656599915, 7271.454640901848, 7282.669545745311, 7290.234525374759, 7321.450891183076, 7063.117110361205, 7122.180949190702, 7144.3570078693165, 7149.571025738473, 7159.964200178966, 7048.3304086113785, 7256.2018177999325, 7265.854682906418, 7283.599402032939, 7312.232215617283, 6597.208334999831, 6892.965535172292, 7142.178649455172, 7173.820654483679, 7188.388986936169], "value": 5288.423328450451, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.5380133615155, 758.2531206761339, 776.4758787818221, 778.2373274191292, 811.691557208261, 15388.846111538882, 15592.298346574404, 15753.291101900413, 15761.842381576194, 15781.13203773792, 14297.142171469719, 14345.067570607904, 14373.20947347898, 14389.0, 14392.208428536427, 784.5410434895564, 797.0913158998746, 934.411320867854, 945.135201290818, 947.1712251779693, 962.7689354554926, 965.9855102173473, 966.246327864267, 967.6129548180713, 970.5972021611022, 1017.8473229015669, 1020.0513522424143, 1020.9897901020969, 1022.7392015036155, 1028.2545154762774, 820.122468958215, 998.4059484606647, 1006.9345492542961, 1012.5595366015807, 1031.5718976624692, 757.4508481351031, 758.9943325094217, 759.965801538932, 760.8630446519625, 765.6592816196795, 15619.453319133889, 15668.529944101696, 15693.979891307028, 15701.371945122182, 15710.764338534931, 12706.55527056558, 12739.044571657163, 12872.357922121928, 12965.222086674736, 13047.217166970013, 10146.137578305852, 10167.0, 10266.897331026743, 10385.16918646514, 10423.0, 3510.2102027043966, 3578.6958108560802, 3602.7297952653394, 3606.747527673064, 3679.0, 85686.28627427478, 86312.56843715742, 86420.0, 86580.0, 86953.695694564, 9683.176929961031, 9688.176504997013, 9783.0, 9927.30508864387, 10145.898541014643, 23083.884580577327, 23109.0, 23111.306660800205, 23144.0, 23192.884035579715, 7101.82245443868, 7213.711451541984, 7317.37802286801, 7321.0, 7323.194448610649, 4156.06488540076, 4197.370394440797, 4226.556211597738, 4321.611055005064, 4334.111507141029, 3763.6612704856693, 3798.525184351956, 3826.0626146594263, 3834.7315687902137, 3837.6354246346555, 590.6101972697999, 660.7588230295908, 671.8310140355843, 681.6591704147926, 696.926732831444, 473.9928901066487, 522.2557855056605, 604.9485793707585, 634.5241069198102, 644.7904431059924, 7999.960000200078, 8325.212028527005, 8348.248657620843, 8464.238218560358, 8713.564321783886, 575.8301301116181, 579.6811753535538, 585.8271809816115, 614.5114633866087, 634.8063840528683, 3794.6395092466173, 3900.0, 3939.1333906540517, 3966.8809935701397, 4003.919921601552, 94773.10453790886, 95269.0, 95608.52195738976, 96760.0, 96911.0, 79159.0, 79556.0, 80488.19511804925, 81098.0, 81122.0, 67512.0, 67750.66124669345, 68875.3112468869, 69092.654536727, 69639.65180174066, 7043.683034263573, 8320.70877519284, 8584.184502472255, 8638.395312328328, 8672.956635217037, 47629.0, 47663.76168119137, 47756.52243477521, 50243.74878125732, 51404.48595513997, 61709.0, 62882.0, 63773.0, 64696.67651661712, 71971.64014180105, 11061.557537698402, 11376.694440989926, 11606.187566870238, 11620.709482262673, 11627.59303424376, 12018.399080046138, 12229.14395992272, 12500.124991250888, 12545.49818007306, 12723.745525089262, 11556.653300400665, 11587.304761714324, 11690.76618467643, 11911.583094591648, 12169.2698438094, 11509.712257193638, 11701.414929253333, 11717.238379505305, 11722.069482629342, 11762.94118529402, 11209.55161793519, 11487.885121148682, 11675.591354302558, 12111.939440302742, 12189.75620487603, 8584.442011269248, 9689.563969621526, 9796.608135674493, 10172.237082219011, 10174.492496473695, 9815.852762208715, 9910.405375677483, 9959.302848800804, 9959.551820167964, 10124.754102442732], "value": 7101.333426802694, "unit": "score"}], "name": "dromaeo_css", "value": 7818.60783817453, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 114.0, 110.0, 110.0, 110.0, 113.0, 112.0, 109.0, 134.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 121.0, 138.0, 162.0, 119.0, 135.0, 117.0, 149.0, 152.0, 118.0], "value": 136.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 185.0, 181.0, 185.0, 185.0, 185.0, 182.0, 182.0, 183.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 101.0, 159.0, 207.0, 56.0, 54.0, 55.0, 61.0, 131.0, 56.0], "value": 95.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 146.0, 88.0, 116.0, 119.0, 134.0, 78.0, 76.0, 126.0], "value": 106.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 86.0, 88.0, 84.0, 88.0, 86.0, 90.0, 86.0], "value": 87.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 100.0, 139.0, 94.0, 93.0, 92.0, 92.0, 93.0, 97.0, 93.0], "value": 98.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 85.0, 85.0, 86.0, 85.0, 86.0, 84.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 63.0, 63.0, 62.0, 61.0, 62.0, 63.0, 78.0, 58.0, 56.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 68.0, 75.0, 73.0, 69.0, 66.0, 66.0, 66.0, 67.0], "value": 67.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 79.0, 80.0, 79.0, 80.0, 80.0, 78.0, 102.0, 105.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 146.0, 119.0, 123.0, 124.0, 126.0, 123.0, 128.0, 146.0, 142.0], "value": 130.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 162.0, 216.0, 216.0, 156.0, 201.0, 216.0, 166.0, 164.0], "value": 182.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 66.0, 66.0, 66.0, 64.0, 67.0, 67.0, 65.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1499.7000000000003, "alertThreshold": 2.0}]} 01:47:05 INFO - Return code: 0 01:47:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 01:47:06 ERROR - # TBPL SUCCESS # 01:47:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:47:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:47:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:47:06 INFO - 2016-06-28 01:47:06.050 screenresolution[1675:9637] starting screenresolution argv=screenresolution get 01:47:06 INFO - 2016-06-28 01:47:06.053 screenresolution[1675:9637] Display 0: 1600x1200x32@60 01:47:06 INFO - 2016-06-28 01:47:06.058 screenresolution[1676:9640] starting screenresolution argv=screenresolution list 01:47:06 INFO - Available Modes on Display 0 01:47:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:47:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:47:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:47:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:47:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:47:06 INFO - Intel Iris: 01:47:06 INFO - Chipset Model: Intel Iris 01:47:06 INFO - Type: GPU 01:47:06 INFO - Bus: Built-In 01:47:06 INFO - VRAM (Dynamic, Max): 1536 MB 01:47:06 INFO - Vendor: Intel (0x8086) 01:47:06 INFO - Device ID: 0x0a2e 01:47:06 INFO - Revision ID: 0x0009 01:47:06 INFO - Displays: 01:47:06 INFO - Display: 01:47:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:47:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:47:06 INFO - Display Serial Number: 1600x1200 60 01:47:06 INFO - Main Display: Yes 01:47:06 INFO - Mirror: Off 01:47:06 INFO - Online: Yes 01:47:06 INFO - Rotation: Supported 01:47:06 INFO - Return code: 0 01:47:06 INFO - Running post-action listener: _resource_record_post_action 01:47:06 INFO - Running post-run listener: _resource_record_post_run 01:47:07 INFO - Total resource usage - Wall time: 1195s; CPU: 52.0%; Read bytes: 38147584; Write bytes: 492479488; Read time: 450; Write time: 1911 01:47:07 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 189239808; Write bytes: 186472448; Read time: 17504; Write time: 490 01:47:07 INFO - run-tests - Wall time: 1174s; CPU: 52.0%; Read bytes: 36341248; Write bytes: 295218176; Read time: 429; Write time: 1392 01:47:07 INFO - Running post-run listener: _upload_blobber_files 01:47:07 INFO - Blob upload gear active. 01:47:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:47:07 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 01:47:07 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'] 01:47:07 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 01:47:07 INFO - (blobuploader) - INFO - Open directory for files ... 01:47:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:47:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:47:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:08 INFO - (blobuploader) - INFO - Done attempting. 01:47:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:47:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:47:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:09 INFO - (blobuploader) - INFO - Done attempting. 01:47:09 INFO - (blobuploader) - INFO - Iteration through files over. 01:47:09 INFO - Return code: 0 01:47:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:47:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:47:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c85e1fadec851bcaf52324f1dad73eee1238a8bd52afca456144aaf5dd9e6c3ea503cda3cf2414f0481b399ea3f31633a3a98912bb495b7c392e8a6ec7734998", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c19266bf679be48be83f247591e53d4bcb1c35eaa6ad665bc05d705eaa3fcac7f8ff18b4c4a325088400d27586a84f504437804332f551a23ea05bd43df3b82"} 01:47:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:47:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:47:09 INFO - Contents: 01:47:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c85e1fadec851bcaf52324f1dad73eee1238a8bd52afca456144aaf5dd9e6c3ea503cda3cf2414f0481b399ea3f31633a3a98912bb495b7c392e8a6ec7734998", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c19266bf679be48be83f247591e53d4bcb1c35eaa6ad665bc05d705eaa3fcac7f8ff18b4c4a325088400d27586a84f504437804332f551a23ea05bd43df3b82"} 01:47:09 INFO - Running post-run listener: copy_logs_to_upload_dir 01:47:09 INFO - Copying logs to upload dir... 01:47:09 INFO - mkdir: /builds/slave/test/build/upload/logs 01:47:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.693345 ========= master_lag: 244.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 55 secs) (at 2016-06-28 01:51:14.038669) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-06-28 01:51:14.042845) ========= 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.q0XjjJinlU/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.uyO91KP3PQ/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c85e1fadec851bcaf52324f1dad73eee1238a8bd52afca456144aaf5dd9e6c3ea503cda3cf2414f0481b399ea3f31633a3a98912bb495b7c392e8a6ec7734998", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c19266bf679be48be83f247591e53d4bcb1c35eaa6ad665bc05d705eaa3fcac7f8ff18b4c4a325088400d27586a84f504437804332f551a23ea05bd43df3b82"} build_url:https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.012031 build_url: 'https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c85e1fadec851bcaf52324f1dad73eee1238a8bd52afca456144aaf5dd9e6c3ea503cda3cf2414f0481b399ea3f31633a3a98912bb495b7c392e8a6ec7734998", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c19266bf679be48be83f247591e53d4bcb1c35eaa6ad665bc05d705eaa3fcac7f8ff18b4c4a325088400d27586a84f504437804332f551a23ea05bd43df3b82"}' ========= master_lag: 2.21 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-06-28 01:51:16.265376) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 01:51:16.265841) ========= 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.q0XjjJinlU/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.uyO91KP3PQ/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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.005204 ========= master_lag: 0.21 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 01:51:16.485422) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-28 01:51:16.485925) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-28 01:51:16.525700) ========= ========= Total master_lag: 246.58 =========