builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0016 starttime: 1462403581.75 results: success (0) buildid: 20160504143046 builduid: 481ab979ab6a4ed19e48cfbc72a08034 revision: 360e0a95af6f0744dbbb84ebcd9cd585893465a4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-04 16:13:01.749581) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-04 16:13:01.750285) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-04 16:13:01.753846) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-04 16:13:02.500839) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-05-04 16:13:02.501228) ========= 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.0nZdQxsKtv/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.3GWjnbhFVk/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-04 16:13:02-- 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% 71.9M=0s 2016-05-04 16:13:02 (71.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124186 ========= master_lag: 5.33 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-05-04 16:13:07.958512) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-05-04 16:13:07.958865) ========= 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.0nZdQxsKtv/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.3GWjnbhFVk/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.027142 ========= master_lag: 1.21 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-05-04 16:13:09.199098) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2016-05-04 16:13:09.201687) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 360e0a95af6f0744dbbb84ebcd9cd585893465a4 --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 360e0a95af6f0744dbbb84ebcd9cd585893465a4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0nZdQxsKtv/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.3GWjnbhFVk/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-04 16:13:13,820 truncating revision to first 12 chars 2016-05-04 16:13:13,820 Setting DEBUG logging. 2016-05-04 16:13:13,820 attempt 1/10 2016-05-04 16:13:13,820 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/360e0a95af6f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-04 16:13:15,540 unpacking tar archive at: mozilla-beta-360e0a95af6f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.973849 ========= master_lag: 9.14 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2016-05-04 16:13:20.316273) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-04 16:13:20.316630) ========= script_repo_revision: 360e0a95af6f0744dbbb84ebcd9cd585893465a4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-04 16:13:20.317021) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-04 16:13:20.317283) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-04 16:13:20.824267) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-05-04 16:13:20.824735) ========= /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.0nZdQxsKtv/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.3GWjnbhFVk/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 16:13:21 INFO - MultiFileLogger online at 20160504 16:13:21 in /builds/slave/test 16:13:21 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 16:13:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:13:21 INFO - {'append_to_log': False, 16:13:21 INFO - 'base_work_dir': '/builds/slave/test', 16:13:21 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:13:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:13:21 INFO - 'branch': 'Mozilla-Beta', 16:13:21 INFO - 'buildbot_json_path': 'buildprops.json', 16:13:21 INFO - 'config_files': ('talos/mac_config.py',), 16:13:21 INFO - 'default_actions': ('clobber', 16:13:21 INFO - 'read-buildbot-config', 16:13:21 INFO - 'download-and-extract', 16:13:21 INFO - 'populate-webroot', 16:13:21 INFO - 'create-virtualenv', 16:13:21 INFO - 'install', 16:13:21 INFO - 'run-tests'), 16:13:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:13:21 INFO - 'download_minidump_stackwalk': True, 16:13:21 INFO - 'download_symbols': 'ondemand', 16:13:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:13:21 INFO - 'tooltool.py': '/tools/tooltool.py', 16:13:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:13:21 INFO - '/tools/misc-python/virtualenv.py')}, 16:13:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:13:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:13:21 INFO - 'installer_path': 'installer.exe', 16:13:21 INFO - 'log_level': 'info', 16:13:21 INFO - 'log_name': 'talos', 16:13:21 INFO - 'log_to_console': True, 16:13:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:13:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:13:21 INFO - 'opt_config_files': (), 16:13:21 INFO - 'pip_index': False, 16:13:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:13:21 INFO - 'cmd': ('bash', 16:13:21 INFO - '-c', 16:13:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:13:21 INFO - 'enabled': True, 16:13:21 INFO - 'halt_on_failure': False, 16:13:21 INFO - 'name': 'check_screen_resolution'},), 16:13:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:13:21 INFO - 'cmd': ('bash', 16:13:21 INFO - '-c', 16:13:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:13:21 INFO - 'enabled': True, 16:13:21 INFO - 'halt_on_failure': False, 16:13:21 INFO - 'name': 'check_screen_resolution'},), 16:13:21 INFO - 'run_cmd_checks_enabled': True, 16:13:21 INFO - 'sps_profile': False, 16:13:21 INFO - 'sps_profile_interval': 0, 16:13:21 INFO - 'suite': 'dromaeojs', 16:13:21 INFO - 'system_bits': '32', 16:13:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:13:21 INFO - 'title': 't-yosemite-r7-0016', 16:13:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:13:21 INFO - 'use_talos_json': True, 16:13:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:13:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:13:21 INFO - 'work_dir': 'build'} 16:13:21 INFO - ##### 16:13:21 INFO - ##### Running clobber step. 16:13:21 INFO - ##### 16:13:21 INFO - Running pre-action listener: _resource_record_pre_action 16:13:21 INFO - Running main action method: clobber 16:13:21 INFO - rmtree: /builds/slave/test/build 16:13:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:13:23 INFO - Running post-action listener: _resource_record_post_action 16:13:23 INFO - ##### 16:13:23 INFO - ##### Running read-buildbot-config step. 16:13:23 INFO - ##### 16:13:23 INFO - Running pre-action listener: _resource_record_pre_action 16:13:23 INFO - Running main action method: read_buildbot_config 16:13:23 INFO - Using buildbot properties: 16:13:23 INFO - { 16:13:23 INFO - "project": "", 16:13:23 INFO - "product": "firefox", 16:13:23 INFO - "script_repo_revision": "production", 16:13:23 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 16:13:23 INFO - "repository": "", 16:13:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 16:13:23 INFO - "buildid": "20160504143046", 16:13:23 INFO - "pgo_build": "False", 16:13:23 INFO - "basedir": "/builds/slave/test", 16:13:23 INFO - "buildnumber": 169, 16:13:23 INFO - "slavename": "t-yosemite-r7-0016", 16:13:23 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 16:13:23 INFO - "platform": "yosemite_r7", 16:13:23 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 16:13:23 INFO - "branch": "mozilla-beta", 16:13:23 INFO - "repo_path": "releases/mozilla-beta", 16:13:23 INFO - "revision": "360e0a95af6f0744dbbb84ebcd9cd585893465a4", 16:13:23 INFO - "stage_platform": "macosx64", 16:13:23 INFO - "builduid": "481ab979ab6a4ed19e48cfbc72a08034", 16:13:23 INFO - "slavebuilddir": "test" 16:13:23 INFO - } 16:13:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 16:13:23 INFO - Running post-action listener: _resource_record_post_action 16:13:23 INFO - ##### 16:13:23 INFO - ##### Running download-and-extract step. 16:13:23 INFO - ##### 16:13:23 INFO - Running pre-action listener: _resource_record_pre_action 16:13:23 INFO - Running main action method: download_and_extract 16:13:23 INFO - mkdir: /builds/slave/test/build/tests 16:13:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:23 INFO - https://queue.taskcluster.net/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:13:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 16:13:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 16:13:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 16:13:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 16:13:26 INFO - Downloaded 1241 bytes. 16:13:26 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 16:13:26 INFO - Using the following test package requirements: 16:13:26 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:13:26 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:13:26 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 16:13:26 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:13:26 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 16:13:26 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 16:13:26 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:13:26 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 16:13:26 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:13:26 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:13:26 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 16:13:26 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:13:26 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 16:13:26 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:13:26 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 16:13:26 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:13:26 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:13:26 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 16:13:26 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 16:13: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')]} 16:13:26 INFO - https://queue.taskcluster.net/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:13:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:13:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:13:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 16:13:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 16:13:28 INFO - Downloaded 17747431 bytes. 16:13:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:13:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:13:31 INFO - Return code: 0 16:13:31 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 16:13: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')]} 16:13:31 INFO - https://queue.taskcluster.net/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:13:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:13:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:13:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 16:13:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 16:13:32 INFO - Downloaded 17747431 bytes. 16:13:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:13:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:13:36 INFO - Return code: 0 16:13:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:36 INFO - https://queue.taskcluster.net/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:13:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 16:13:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 16:13:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 16:13:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 16:13:38 INFO - Downloaded 10845194 bytes. 16:13:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:13:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:13:38 INFO - Return code: 0 16:13:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:38 INFO - https://queue.taskcluster.net/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 16:13:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:13:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:13:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 16:13:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 16:13:41 INFO - Downloaded 88347795 bytes. 16:13:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:13:41 INFO - mkdir: /builds/slave/test/properties 16:13:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:13:41 INFO - Writing to file /builds/slave/test/properties/build_url 16:13:41 INFO - Contents: 16:13:41 INFO - build_url:https://queue.taskcluster.net/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:13:44 INFO - Running post-action listener: _resource_record_post_action 16:13:44 INFO - Running post-action listener: set_extra_try_arguments 16:13:44 INFO - ##### 16:13:44 INFO - ##### Running populate-webroot step. 16:13:44 INFO - ##### 16:13:44 INFO - Running pre-action listener: _resource_record_pre_action 16:13:44 INFO - Running main action method: populate_webroot 16:13:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:13:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:13:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:13:44 INFO - '--tppagecycles', 16:13:44 INFO - '7'], 16:13:44 INFO - 'tests': ['tsvgm']}}, 16:13:44 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:13:44 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:13:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:13:44 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 16:13:44 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:13:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:13:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:13:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:44 INFO - 'talos_options': ['--e10s'], 16:13:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:13:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:44 INFO - 'tests': ['damp', 'tps']}, 16:13:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:44 INFO - 'talos_options': ['--e10s'], 16:13:44 INFO - 'tests': ['damp', 'tps']}, 16:13:44 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:13:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:13:44 INFO - 'other': {'tests': ['a11yr', 16:13:44 INFO - 'ts_paint', 16:13:44 INFO - 'tpaint', 16:13:44 INFO - 'sessionrestore', 16:13:44 INFO - 'sessionrestore_no_auto_restore', 16:13:44 INFO - 'tabpaint']}, 16:13:44 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:13:44 INFO - 'tests': ['a11yr', 16:13:44 INFO - 'ts_paint', 16:13:44 INFO - 'tpaint', 16:13:44 INFO - 'sessionrestore', 16:13:44 INFO - 'sessionrestore_no_auto_restore', 16:13:44 INFO - 'tabpaint']}, 16:13:44 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:13:44 INFO - 'tests': ['a11yr', 16:13:44 INFO - 'ts_paint', 16:13:44 INFO - 'tpaint', 16:13:44 INFO - 'sessionrestore', 16:13:44 INFO - 'sessionrestore_no_auto_restore', 16:13:44 INFO - 'tabpaint']}, 16:13:44 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:13:44 INFO - 'tests': ['a11yr', 16:13:44 INFO - 'ts_paint', 16:13:44 INFO - 'tpaint', 16:13:44 INFO - 'sessionrestore', 16:13:44 INFO - 'sessionrestore_no_auto_restore', 16:13:44 INFO - 'tabpaint']}, 16:13:44 INFO - 'other_l64': {'tests': ['a11yr', 16:13:44 INFO - 'ts_paint', 16:13:44 INFO - 'tpaint', 16:13:44 INFO - 'sessionrestore', 16:13:44 INFO - 'sessionrestore_no_auto_restore', 16:13:44 INFO - 'tabpaint']}, 16:13:44 INFO - 'other_nol64': {'tests': ['a11yr', 16:13:44 INFO - 'ts_paint', 16:13:44 INFO - 'tpaint', 16:13:44 INFO - 'sessionrestore', 16:13:44 INFO - 'sessionrestore_no_auto_restore', 16:13:44 INFO - 'tabpaint']}, 16:13:44 INFO - 'svgr': {'tests': ['tsvgx', 16:13:44 INFO - 'tsvgr_opacity', 16:13:44 INFO - 'tart', 16:13:44 INFO - 'tscrollx', 16:13:44 INFO - 'cart']}, 16:13:44 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:13:44 INFO - 'tests': ['tsvgx', 16:13:44 INFO - 'tsvgr_opacity', 16:13:44 INFO - 'tart', 16:13:44 INFO - 'tscrollx', 16:13:44 INFO - 'cart']}, 16:13:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:44 INFO - 'tests': ['tp5o']}, 16:13:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:44 INFO - 'talos_options': ['--e10s'], 16:13:44 INFO - 'tests': ['tp5o']}, 16:13:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:44 INFO - 'talos_options': ['--xperf_path', 16:13:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:13:44 INFO - 'tests': ['tp5n']}, 16:13:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:44 INFO - 'talos_options': ['--e10s', 16:13:44 INFO - '--xperf_path', 16:13:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:13:44 INFO - 'tests': ['tp5n']}}, 16:13:44 INFO - 'talos.zip': {'path': '', 16:13:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:13:44 INFO - Running post-action listener: _resource_record_post_action 16:13:44 INFO - ##### 16:13:44 INFO - ##### Running create-virtualenv step. 16:13:44 INFO - ##### 16:13:44 INFO - Running pre-action listener: _resource_record_pre_action 16:13:44 INFO - Running main action method: create_virtualenv 16:13:44 INFO - Creating virtualenv /builds/slave/test/build/venv 16:13:44 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 16:13:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:13:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:13:44 INFO - Using real prefix '/tools/python27' 16:13:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:13:45 INFO - Installing distribute.............................................................................................................................................................................................done. 16:13:47 INFO - Installing pip.................done. 16:13:48 INFO - Return code: 0 16:13:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:48 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 0x104a20a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c2571970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c4d30>, '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 0x104728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bbb20>, '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.0nZdQxsKtv/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.3GWjnbhFVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 16:13:48 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 16:13:48 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 16:13:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nZdQxsKtv/Render', 16:13:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:48 INFO - 'HOME': '/Users/cltbld', 16:13:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:48 INFO - 'LOGNAME': 'cltbld', 16:13:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:48 INFO - 'MOZ_NO_REMOTE': '1', 16:13:48 INFO - 'NO_EM_RESTART': '1', 16:13:48 INFO - 'PAGER': '/bin/cat', 16:13:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:48 INFO - 'PWD': '/builds/slave/test', 16:13:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:48 INFO - 'SHELL': '/bin/bash', 16:13:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GWjnbhFVk/Listeners', 16:13:48 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 16:13:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:48 INFO - 'USER': 'cltbld', 16:13:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:48 INFO - 'XPC_FLAGS': '0x0', 16:13:48 INFO - 'XPC_SERVICE_NAME': '0', 16:13:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:48 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 16:13:48 INFO - Cleaning up... 16:13:48 INFO - Return code: 0 16:13:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:48 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 0x104a20a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c2571970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c4d30>, '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 0x104728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bbb20>, '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.0nZdQxsKtv/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.3GWjnbhFVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 16:13:48 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 16:13:48 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 16:13:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nZdQxsKtv/Render', 16:13:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:48 INFO - 'HOME': '/Users/cltbld', 16:13:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:48 INFO - 'LOGNAME': 'cltbld', 16:13:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:48 INFO - 'MOZ_NO_REMOTE': '1', 16:13:48 INFO - 'NO_EM_RESTART': '1', 16:13:48 INFO - 'PAGER': '/bin/cat', 16:13:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:48 INFO - 'PWD': '/builds/slave/test', 16:13:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:48 INFO - 'SHELL': '/bin/bash', 16:13:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GWjnbhFVk/Listeners', 16:13:48 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 16:13:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:48 INFO - 'USER': 'cltbld', 16:13:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:48 INFO - 'XPC_FLAGS': '0x0', 16:13:48 INFO - 'XPC_SERVICE_NAME': '0', 16:13:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:48 INFO - Downloading/unpacking psutil>=0.7.1 16:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:13:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:13:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:13:51 INFO - Installing collected packages: psutil 16:13:51 INFO - Running setup.py install for psutil 16:13:51 INFO - building 'psutil._psutil_osx' extension 16:13:51 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 16:13:52 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 16:13:52 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 16:13:52 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 16:13:52 INFO - building 'psutil._psutil_posix' extension 16:13:52 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 16:13:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:13:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:13:52 INFO - ^ 16:13:52 INFO - 1 warning generated. 16:13:52 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 16:13:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:13:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:13:52 INFO - Successfully installed psutil 16:13:52 INFO - Cleaning up... 16:13:52 INFO - Return code: 0 16:13:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:13:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:52 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 0x104a20a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c2571970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c4d30>, '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 0x104728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bbb20>, '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.0nZdQxsKtv/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.3GWjnbhFVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 16:13:52 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 16:13:52 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 16:13:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nZdQxsKtv/Render', 16:13:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:52 INFO - 'HOME': '/Users/cltbld', 16:13:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:52 INFO - 'LOGNAME': 'cltbld', 16:13:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:52 INFO - 'MOZ_NO_REMOTE': '1', 16:13:52 INFO - 'NO_EM_RESTART': '1', 16:13:52 INFO - 'PAGER': '/bin/cat', 16:13:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:52 INFO - 'PWD': '/builds/slave/test', 16:13:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:52 INFO - 'SHELL': '/bin/bash', 16:13:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GWjnbhFVk/Listeners', 16:13:52 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 16:13:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:52 INFO - 'USER': 'cltbld', 16:13:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:52 INFO - 'XPC_FLAGS': '0x0', 16:13:52 INFO - 'XPC_SERVICE_NAME': '0', 16:13:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:13:52 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 16:13:52 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 16:13:52 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 16:13:52 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 16:13:52 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 16:13:52 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 16:13:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:13:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:13:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:13:55 INFO - Installing collected packages: mozsystemmonitor 16:13:55 INFO - Running setup.py install for mozsystemmonitor 16:13:55 INFO - Successfully installed mozsystemmonitor 16:13:55 INFO - Cleaning up... 16:13:55 INFO - Return code: 0 16:13:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:13:55 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')]} 16:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:55 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')]} 16:13:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:55 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 0x104a20a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c2571970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c4d30>, '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 0x104728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bbb20>, '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.0nZdQxsKtv/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.3GWjnbhFVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 16:13:55 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 16:13:55 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 16:13:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nZdQxsKtv/Render', 16:13:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:55 INFO - 'HOME': '/Users/cltbld', 16:13:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:55 INFO - 'LOGNAME': 'cltbld', 16:13:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:55 INFO - 'MOZ_NO_REMOTE': '1', 16:13:55 INFO - 'NO_EM_RESTART': '1', 16:13:55 INFO - 'PAGER': '/bin/cat', 16:13:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:55 INFO - 'PWD': '/builds/slave/test', 16:13:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:55 INFO - 'SHELL': '/bin/bash', 16:13:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GWjnbhFVk/Listeners', 16:13:55 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 16:13:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:55 INFO - 'USER': 'cltbld', 16:13:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:55 INFO - 'XPC_FLAGS': '0x0', 16:13:55 INFO - 'XPC_SERVICE_NAME': '0', 16:13:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:55 INFO - Downloading/unpacking blobuploader==1.2.4 16:13:55 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 16:13:55 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 16:13:55 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 16:13:55 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 16:13:55 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 16:13:55 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 16:13:58 INFO - Downloading blobuploader-1.2.4.tar.gz 16:13:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:13:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:13:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:13:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:59 INFO - Downloading docopt-0.6.1.tar.gz 16:13:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:13:59 INFO - Installing collected packages: blobuploader, requests, docopt 16:13:59 INFO - Running setup.py install for blobuploader 16:13:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:13:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:13:59 INFO - Running setup.py install for requests 16:14:00 INFO - Running setup.py install for docopt 16:14:00 INFO - Successfully installed blobuploader requests docopt 16:14:00 INFO - Cleaning up... 16:14:00 INFO - Return code: 0 16:14:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:00 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 0x104a20a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c2571970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c4d30>, '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 0x104728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bbb20>, '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.0nZdQxsKtv/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.3GWjnbhFVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 16:14:00 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 16:14:00 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 16:14:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nZdQxsKtv/Render', 16:14:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:00 INFO - 'HOME': '/Users/cltbld', 16:14:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:00 INFO - 'LOGNAME': 'cltbld', 16:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:00 INFO - 'MOZ_NO_REMOTE': '1', 16:14:00 INFO - 'NO_EM_RESTART': '1', 16:14:00 INFO - 'PAGER': '/bin/cat', 16:14:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:00 INFO - 'PWD': '/builds/slave/test', 16:14:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:00 INFO - 'SHELL': '/bin/bash', 16:14:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GWjnbhFVk/Listeners', 16:14:00 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 16:14:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:00 INFO - 'USER': 'cltbld', 16:14:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:00 INFO - 'XPC_FLAGS': '0x0', 16:14:00 INFO - 'XPC_SERVICE_NAME': '0', 16:14:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:14:00 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-pdR_xv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:14:00 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Exhy_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:14:00 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Is9aYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:14:01 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-oqnkmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:14:01 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-f02IxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:14:01 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-NHtOW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:14:01 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-tYScFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:14:01 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-olfrZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:14:01 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-cbpTlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:14:01 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-68TBm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:14:01 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-bD5Jb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:14:01 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-d_3hjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:14:01 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-3lpQsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:14:02 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-P9tlg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:14:02 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-lYpRlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:14:02 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-MhRIbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:14:02 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-KIjhIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:14:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:14:02 INFO - Running setup.py install for manifestparser 16:14:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:14:02 INFO - Running setup.py install for mozcrash 16:14:02 INFO - Running setup.py install for mozdebug 16:14:02 INFO - Running setup.py install for mozdevice 16:14:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:14:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:14:03 INFO - Running setup.py install for mozfile 16:14:03 INFO - Running setup.py install for mozhttpd 16:14:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:14:03 INFO - Running setup.py install for mozinfo 16:14:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:14:03 INFO - Running setup.py install for mozInstall 16:14:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:14:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:14:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:14:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:14:03 INFO - Running setup.py install for mozleak 16:14:03 INFO - Running setup.py install for mozlog 16:14:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:14:04 INFO - Running setup.py install for moznetwork 16:14:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:14:04 INFO - Running setup.py install for mozprocess 16:14:04 INFO - Running setup.py install for mozprofile 16:14:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:14:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:14:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:14:04 INFO - Running setup.py install for mozrunner 16:14:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:14:04 INFO - Running setup.py install for mozscreenshot 16:14:04 INFO - Running setup.py install for moztest 16:14:04 INFO - Running setup.py install for mozversion 16:14:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:14:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:14:05 INFO - Cleaning up... 16:14:05 INFO - Return code: 0 16:14:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:14: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')]} 16:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14: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')]} 16:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:05 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 0x104a20a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c2571970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c4d30>, '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 0x104728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bbb20>, '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.0nZdQxsKtv/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.3GWjnbhFVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 16:14:05 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 16:14:05 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 16:14:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nZdQxsKtv/Render', 16:14:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:05 INFO - 'HOME': '/Users/cltbld', 16:14:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:05 INFO - 'LOGNAME': 'cltbld', 16:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:05 INFO - 'MOZ_NO_REMOTE': '1', 16:14:05 INFO - 'NO_EM_RESTART': '1', 16:14:05 INFO - 'PAGER': '/bin/cat', 16:14:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:05 INFO - 'PWD': '/builds/slave/test', 16:14:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:05 INFO - 'SHELL': '/bin/bash', 16:14:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GWjnbhFVk/Listeners', 16:14:05 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 16:14:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:05 INFO - 'USER': 'cltbld', 16:14:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:05 INFO - 'XPC_FLAGS': '0x0', 16:14:05 INFO - 'XPC_SERVICE_NAME': '0', 16:14:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:14:05 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-i3TyNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:14:05 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)) 16:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:14:05 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-h31kJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:14:05 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)) 16:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:14:05 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-sl0vCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:14:05 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)) 16:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:14:05 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-9l5wc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:14:05 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)) 16:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:14:05 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-eQUt7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:14:05 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)) 16:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:14:05 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-jYljmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:14:06 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)) 16:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:14:06 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-EgtODR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:14:06 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)) 16:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:14:06 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Sl3aKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:14:06 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)) 16:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:14:06 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Ycxc1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:14:06 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)) 16:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:14:06 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-c8OK74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:14:06 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)) 16:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:14:06 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-WD6vGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:14:06 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)) 16:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:14:06 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-fKFj4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:14:06 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)) 16:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:14:06 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-GUe3gt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:14:06 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)) 16:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:14:06 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-qqbnkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:14:06 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)) 16:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:14:06 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-WahfF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:14:06 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)) 16:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:14:06 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-NqMANI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:14:07 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)) 16:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:14:07 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-bwvhQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:14:07 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)) 16:14:07 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)) 16:14:07 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)) 16:14:07 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)) 16:14:07 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)) 16:14:07 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)) 16:14:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:09 INFO - Downloading blessings-1.6.tar.gz 16:14:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:14:10 INFO - Installing collected packages: blessings 16:14:10 INFO - Running setup.py install for blessings 16:14:10 INFO - Successfully installed blessings 16:14:10 INFO - Cleaning up... 16:14:10 INFO - Return code: 0 16:14:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:14:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:14:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:14:10 INFO - Reading from file tmpfile_stdout 16:14:10 INFO - Current package versions: 16:14:10 INFO - blessings == 1.6 16:14:10 INFO - blobuploader == 1.2.4 16:14:10 INFO - docopt == 0.6.1 16:14:10 INFO - manifestparser == 1.1 16:14:10 INFO - mozInstall == 1.12 16:14:10 INFO - mozcrash == 0.17 16:14:10 INFO - mozdebug == 0.1 16:14:10 INFO - mozdevice == 0.48 16:14:10 INFO - mozfile == 1.2 16:14:10 INFO - mozhttpd == 0.7 16:14:10 INFO - mozinfo == 0.9 16:14:10 INFO - mozleak == 0.1 16:14:10 INFO - mozlog == 3.1 16:14:10 INFO - moznetwork == 0.27 16:14:10 INFO - mozprocess == 0.22 16:14:10 INFO - mozprofile == 0.28 16:14:10 INFO - mozrunner == 6.11 16:14:10 INFO - mozscreenshot == 0.1 16:14:10 INFO - mozsystemmonitor == 0.0 16:14:10 INFO - moztest == 0.7 16:14:10 INFO - mozversion == 1.4 16:14:10 INFO - psutil == 3.1.1 16:14:10 INFO - requests == 1.2.3 16:14:10 INFO - wsgiref == 0.1.2 16:14:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:14:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:10 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 0x104a20a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c2571970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c4d30>, '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 0x104728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bbb20>, '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.0nZdQxsKtv/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.3GWjnbhFVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 16:14:10 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 16:14:10 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 16:14:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nZdQxsKtv/Render', 16:14:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:10 INFO - 'HOME': '/Users/cltbld', 16:14:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:10 INFO - 'LOGNAME': 'cltbld', 16:14:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:10 INFO - 'MOZ_NO_REMOTE': '1', 16:14:10 INFO - 'NO_EM_RESTART': '1', 16:14:10 INFO - 'PAGER': '/bin/cat', 16:14:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:10 INFO - 'PWD': '/builds/slave/test', 16:14:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:10 INFO - 'SHELL': '/bin/bash', 16:14:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GWjnbhFVk/Listeners', 16:14:10 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 16:14:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:10 INFO - 'USER': 'cltbld', 16:14:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:10 INFO - 'XPC_FLAGS': '0x0', 16:14:10 INFO - 'XPC_SERVICE_NAME': '0', 16:14:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 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)) 16:14:10 INFO - Cleaning up... 16:14:10 INFO - Return code: 0 16:14:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:14:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a20a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c2571970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c4d30>, '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 0x104728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bbb20>, '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.0nZdQxsKtv/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.3GWjnbhFVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 16:14:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:14:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:14:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nZdQxsKtv/Render', 16:14:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:10 INFO - 'HOME': '/Users/cltbld', 16:14:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:10 INFO - 'LOGNAME': 'cltbld', 16:14:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:10 INFO - 'MOZ_NO_REMOTE': '1', 16:14:10 INFO - 'NO_EM_RESTART': '1', 16:14:10 INFO - 'PAGER': '/bin/cat', 16:14:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:10 INFO - 'PWD': '/builds/slave/test', 16:14:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:10 INFO - 'SHELL': '/bin/bash', 16:14:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GWjnbhFVk/Listeners', 16:14:10 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 16:14:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:10 INFO - 'USER': 'cltbld', 16:14:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:10 INFO - 'XPC_FLAGS': '0x0', 16:14:10 INFO - 'XPC_SERVICE_NAME': '0', 16:14:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:11 INFO - Downloading/unpacking jsonschema 16:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:14:13 INFO - Downloading/unpacking functools32 (from jsonschema) 16:14:13 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 16:14:13 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 16:14:13 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 16:14:13 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 16:14:13 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 16:14:13 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 16:14:14 INFO - Downloading functools32-3.2.3-2.tar.gz 16:14:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:14:14 INFO - warning: no files found matching '*.txt' 16:14:14 INFO - no previously-included directories found matching 'build' 16:14:14 INFO - no previously-included directories found matching 'dist' 16:14:14 INFO - no previously-included directories found matching '.git*' 16:14:14 INFO - Installing collected packages: jsonschema, functools32 16:14:14 INFO - Running setup.py install for functools32 16:14:14 INFO - warning: no files found matching '*.txt' 16:14:14 INFO - no previously-included directories found matching 'build' 16:14:14 INFO - no previously-included directories found matching 'dist' 16:14:14 INFO - no previously-included directories found matching '.git*' 16:14:14 INFO - Successfully installed jsonschema functools32 16:14:14 INFO - Cleaning up... 16:14:14 INFO - Return code: 0 16:14:14 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a20a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a274c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8c2571970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048c4d30>, '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 0x104728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bbb20>, '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.0nZdQxsKtv/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.3GWjnbhFVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 16:14:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 16:14:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:14:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nZdQxsKtv/Render', 16:14:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:14 INFO - 'HOME': '/Users/cltbld', 16:14:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:14 INFO - 'LOGNAME': 'cltbld', 16:14:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:14 INFO - 'MOZ_NO_REMOTE': '1', 16:14:14 INFO - 'NO_EM_RESTART': '1', 16:14:14 INFO - 'PAGER': '/bin/cat', 16:14:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:14 INFO - 'PWD': '/builds/slave/test', 16:14:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:14 INFO - 'SHELL': '/bin/bash', 16:14:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GWjnbhFVk/Listeners', 16:14:14 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 16:14:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:14 INFO - 'USER': 'cltbld', 16:14:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:14 INFO - 'XPC_FLAGS': '0x0', 16:14:14 INFO - 'XPC_SERVICE_NAME': '0', 16:14:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:14 INFO - Downloading/unpacking flake8 16:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:14:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:14:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:14:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:14:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:14:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:14:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:14:18 INFO - no previously-included directories found matching 'docs/_build' 16:14:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:14:18 INFO - Running setup.py install for pep8 16:14:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:14:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:14:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:14:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:14:18 INFO - no previously-included directories found matching 'docs/_build' 16:14:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:14:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:14:18 INFO - Cleaning up... 16:14:18 INFO - Return code: 0 16:14:18 INFO - Running post-action listener: _resource_record_post_action 16:14:18 INFO - Running post-action listener: _start_resource_monitoring 16:14:18 INFO - Starting resource monitoring. 16:14:18 INFO - ##### 16:14:18 INFO - ##### Running install step. 16:14:18 INFO - ##### 16:14:18 INFO - Running pre-action listener: _resource_record_pre_action 16:14:18 INFO - Running main action method: install 16:14:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:14:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:14:19 INFO - Reading from file tmpfile_stdout 16:14:19 INFO - Detecting whether we're running mozinstall >=1.0... 16:14:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:14:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:14:19 INFO - Reading from file tmpfile_stdout 16:14:19 INFO - Output received: 16:14:19 INFO - Usage: mozinstall [options] installer 16:14:19 INFO - Options: 16:14:19 INFO - -h, --help show this help message and exit 16:14:19 INFO - -d DEST, --destination=DEST 16:14:19 INFO - Directory to install application into. [default: 16:14:19 INFO - "/builds/slave/test"] 16:14:19 INFO - --app=APP Application being installed. [default: firefox] 16:14:19 INFO - mkdir: /builds/slave/test/build/application 16:14:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:14:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 16:14:41 INFO - Reading from file tmpfile_stdout 16:14:41 INFO - Output received: 16:14:41 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 16:14:41 INFO - Running post-action listener: _resource_record_post_action 16:14:41 INFO - ##### 16:14:41 INFO - ##### Running run-tests step. 16:14:41 INFO - ##### 16:14:41 INFO - Running pre-action listener: _resource_record_pre_action 16:14:41 INFO - Running main action method: run_tests 16:14:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:14:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:14:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:14:41 INFO - 2016-05-04 16:14:41.517 screenresolution[1639:7720] starting screenresolution argv=screenresolution get 16:14:41 INFO - 2016-05-04 16:14:41.520 screenresolution[1639:7720] Display 0: 1600x1200x32@60 16:14:41 INFO - 2016-05-04 16:14:41.525 screenresolution[1640:7722] starting screenresolution argv=screenresolution list 16:14:41 INFO - Available Modes on Display 0 16:14:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:14:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:14:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:14:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:14:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:14:41 INFO - Intel Iris: 16:14:41 INFO - Chipset Model: Intel Iris 16:14:41 INFO - Type: GPU 16:14:41 INFO - Bus: Built-In 16:14:41 INFO - VRAM (Dynamic, Max): 1536 MB 16:14:41 INFO - Vendor: Intel (0x8086) 16:14:41 INFO - Device ID: 0x0a2e 16:14:41 INFO - Revision ID: 0x0009 16:14:41 INFO - Displays: 16:14:41 INFO - Display: 16:14:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:14:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:14:41 INFO - Display Serial Number: 1600x1200 60 16:14:41 INFO - Main Display: Yes 16:14:41 INFO - Mirror: Off 16:14:41 INFO - Online: Yes 16:14:41 INFO - Rotation: Supported 16:14:41 INFO - Return code: 0 16:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:14:41 INFO - Python 2.7.3 16:14:41 INFO - Return code: 0 16:14:41 INFO - grabbing minidump binary from tooltool 16:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:41 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 0x1048c4d30>, '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 0x104728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1048bbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:14:41 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 16:14:41 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 16:14:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:14:41 INFO - Return code: 0 16:14:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:14:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:14:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:14:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:14:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:14:43 INFO - Return code: 0 16:14:43 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/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--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 16:14:43 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/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0016 --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 16:14:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nZdQxsKtv/Render', 16:14:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:43 INFO - 'HOME': '/Users/cltbld', 16:14:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:43 INFO - 'LOGNAME': 'cltbld', 16:14:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:14:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:43 INFO - 'MOZ_NO_REMOTE': '1', 16:14:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:14:43 INFO - 'NO_EM_RESTART': '1', 16:14:43 INFO - 'PAGER': '/bin/cat', 16:14:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:43 INFO - 'PWD': '/builds/slave/test', 16:14:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:14:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:43 INFO - 'SHELL': '/bin/bash', 16:14:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3GWjnbhFVk/Listeners', 16:14:43 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 16:14:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:43 INFO - 'USER': 'cltbld', 16:14:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:43 INFO - 'XPC_FLAGS': '0x0', 16:14:43 INFO - 'XPC_SERVICE_NAME': '0', 16:14:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:43 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/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--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 16:14:43 INFO - mozversion application_buildid: 20160504143046 16:14:43 INFO - mozversion application_changeset: 360e0a95af6f0744dbbb84ebcd9cd585893465a4 16:14:43 INFO - mozversion application_display_name: Firefox 16:14:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:14:43 INFO - mozversion application_name: Firefox 16:14:43 INFO - mozversion application_remotingname: firefox 16:14:43 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:14:43 INFO - mozversion application_vendor: Mozilla 16:14:43 INFO - mozversion application_version: 47.0 16:14:43 INFO - mozversion platform_buildid: 20160504143046 16:14:43 INFO - mozversion platform_changeset: 360e0a95af6f0744dbbb84ebcd9cd585893465a4 16:14:43 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:14:43 INFO - mozversion platform_version: 47.0 16:14:43 INFO - using testdate: 1462403683 16:14:43 INFO - actual date: 1462403683 16:14:43 INFO - starting webserver on 'localhost:49275' 16:14:43 INFO - SUITE-START | Running 2 tests 16:14:43 INFO - TEST-START | dromaeo_css 16:14:43 INFO - operating with platform_type : mac_ 16:14:43 INFO - Initialising browser for dromaeo_css test... 16:14:43 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpgPLFJA/profile http://localhost:49275/getInfo.html) 16:14:45 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 16:14:45 INFO - PROCESS | 1658 | colorDepth:24 16:14:45 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 16:14:45 INFO - PROCESS | 1658 | __metrics 16:14:45 INFO - PROCESS | 1658 | 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" 16:14:45 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:14:46 INFO - TEST-INFO | 1658: exit 0 16:14:46 INFO - Browser initialized. 16:14:46 INFO - Running cycle 1/1 for dromaeo_css test... 16:14:46 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpgPLFJA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:14:58 INFO - PROCESS | 1664 | RSS: Main: 156827648 16:14:58 INFO - PROCESS | 1664 | 16:15:50 INFO - PROCESS | 1664 | 1462403750109 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] 16:17:58 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 16:17:59 INFO - PROCESS | 1664 | RSS: Main: 283377664 16:17:59 INFO - PROCESS | 1664 | 16:21:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 16:21:02 INFO - PROCESS | 1664 | RSS: Main: 295317504 16:21:02 INFO - PROCESS | 1664 | 16:24:04 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 16:24:05 INFO - PROCESS | 1664 | RSS: Main: 293134336 16:24:05 INFO - PROCESS | 1664 | 16:27:07 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 16:27:07 INFO - PROCESS | 1664 | RSS: Main: 253976576 16:27:07 INFO - PROCESS | 1664 | 16:30:10 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 16:30:11 INFO - PROCESS | 1664 | RSS: Main: 254062592 16:30:11 INFO - PROCESS | 1664 | 16:33:14 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 16:33:14 INFO - PROCESS | 1664 | RSS: Main: 301350912 16:33:14 INFO - PROCESS | 1664 | 16:33:14 INFO - PROCESS | 1664 | __start_tp_report 16:33:14 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 16:33:14 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 16:33:14 INFO - PROCESS | 1664 | |i|pagename|runs| 16:33:14 INFO - PROCESS | 1664 | |0;dojo.html;3561.21653236288;3602.3875941090046;3619;3647.379945409281;3699.6855267302276;5897.085951677492;5915.112733090039;5919.822405327836;5947.464728174464;5996.790112346069;5920.7039648017535;5976.2230909981645;5979.461848433651;5981.671008094553;5984.670843103628;5162.664426812245;5259.710715910633;5281.155015197569;5340.946590534103;5383.838484845461;5386.73066346683;5408.432114627966;5418.943306055321;5420.1327787554;5421.430749771276;5355.732213389334;5412.91880621791;5413.052715774744;5444.537214336786;5455.563554915597;4757.429108506966;5425;5468.535174510172;5476.26070480488;5476.534494567986;5252.816151434719;5345.759440825133;5355.7857685692525;5372.221027950925;5399.946000539983;5857.85355366115;5870;5899.46904778572;5904;5925.20009798676;5126.61550383719;6151.861905547444;6215.316315205324;6326.2092238470195;6327.27236367819;6314.463270622003;6319.462845658079;6323.430891219767;6330.240371155444;6345.397187267202;6101.115338275969;6104.511639068864;6105.877882442326;6117.418845209698;6118.3269840317525;6283.654399008007;6418;6480.287168411471;6505.21937367519;6517.152770139946;5009.949900501021;6218.657973811484;6244.0321750128805;6260.593061451083;6295.213098362705;6224.844378890564;6312.873742525216;6323.841903952346;6325;6333.714982825784;6162.26052873658;6180.47465965398;6283.151774510383;6287;6416.518761092937;6269.655168965751;6271.560990730605;6275.937240627627;6293.30773614902;6337.207849018872;4989.351384320088;5063.8987220255385;5068.594512438996;5079.288899554065;5110.872228194251;4461;4534.342520334533;4558.156741002925;4558.202314594932;4600.332951721982;6081.939180608226;6091.14723938649;6107.053406721965;6120.204373431515;6142.324344322121;6158.846028849314;6317.6209427434505;6334.144890439732;6337.493000559944;6354.142190804182;6028.728707208186;6054.455099041107;6090.817275481742;6103.6032657877095;6107.795737660991;5556.444355564411;5557.638753481011;5559.610827242135;5577.247071645276;5587.217789509472;6382.521310901608;6487.578307410004;6566.146400967939;6579.276279609239;6594.538382313288;6265.185525881606;6309.2744334400295;6316.810495685137;6319.842003949938;6493.383128602775;6527.2167339919515;6543.443807276435;6545;6562.606243625398;6575.769848055295;6148.569600128127;6201.286852012076;6254.655993920378;6258.968705156627;6266;6244.937550624617;6294.244690637152;6300.842478938063;6306.180196574416;6491.707873145625;6141.048137538689;6161.815145545641;6213.130161777264;6218.533609979179;6223.128761973418;5120.334356533627;6148.385161483816;6166.229221347332;6170.50635949132;6237.719302631302;6195.690215489298;6220.657863817352;6227.034809604337;6234;6236.6258024518675;5145.768440420115;6205.596636218631;6210.161628180319;6215.316315205233;6241.96879015602;6057.757689692363;6086.934786412272;6117.235345581802;6136.059583474799;6172 16:33:14 INFO - PROCESS | 1664 | |1;ext.html;501.4358846297915;596.120721935146;619.5198720991232;643.4755674125589;671.7984604618614;32011.19972000701;32656.83671581642;32700.83649581752;41444.58555414445;41797.164056718924;44366;44759.77620111903;44912.877178070565;44990.10019799602;45009.09981800362;9634.747482827222;9818.852717209229;10054.195664346835;10083.747906302327;10102.040306170902;8898.644054237822;9988.200943924468;9992.400455972629;9998.600055997751;10001.549930253155;9937.304388692794;9972.85583599526;10007.29948903577;10029.348092373972;10100.696979090639;10013.899861001406;10093;10098.192144628412;10103.747406314827;10110.39337639744;10011.549480273405;10045.54795034218;10154.238432117621;10168.745781355452;10170.033846784543;42922;43374.91562710926;43426.56573434257;43463;43491;3409.2840503494294;3472.6006509251506;3554.591222009476;3560.786352818839;3571.1072231942017;28664;29608.55587166194;29858.85070574655;29891.40217195644;30265.092047238617;2798.720127987205;2820.19624407043;2832.4051949090717;2847.359344147571;2895.4353900989317;476627;487330.56334718456;488587.99999999645;490163;491062;78766.21233787588;79439.20560794318;79512.6024369886;79687.60156199182;79724.60137699274;86683;87613.56193219121;87641;87711;88135.11864881398;18724.25102995865;18726.344577939708;18885.339013134475;18886.716699249522;18891.677582569362;14101.78847317291;14198;14202.928985355007;14212.573622791333;14215.36030878613;24897.62653560198;25084.372890677878;25309.62035569468;25334.98660053614;25375.98496060137;8394.58027098655;8759.124087591317;10592.364458132537;10669.306495077795;10691.30506517074;1016.7000734783256;1082.1721383141903;1125.9887401125884;1164.342146687119;1180.3331117918349;29410;29479.557806632918;29645.407091858044;30139.246518836764;30204.24489387783;1135.1089394825187;1154.520873837365;1165.877582853805;1250.5435516036582;1272.1476610670873;12833.762713883643;15379.923100384427;15627.531174064796;15767.684646307009;15985.120818355103;561986.1900690552;562080;566502;566566;568248;53427.19859202115;63398;63940.04089938655;64034;64047.67976160089;1460.6494441334003;1544.274191130167;1557.6339560203344;1578.6605879735912;1603.8476344747464;30103.496614736472;31686.683133169296;31764.682353176173;32041.35917281688;32458.35083298368;52435.47564524306;52671.73664131655;53092.141775037686;53164.20253696275;53172.73413632907;377611;378867;379480;380038;380641;30872.91944781922;35534.64465355417;36603.816980914926;36648.63351366452;36760.26479470342;44641.55358446374;44678.776606116764;45274.54725452703;45453;45857.77071114623;37015.44476832796;37235;37248.62751372452;37637.811810941865;37692.62307376891;36588.451173232956;36698.63301367059;37012;37342.626573735004;37527;36821.44767828538;38836;39083.89619090068;39363.80318098391;39461.2107757849;33038.5044224332;33306.500402494465;33998.8300058508;34017.80937667269;34044.489332659534;25429.364265893502;25819.225423237334;25832.741672583034;26269.73730262673;26286.079987200355 16:33:14 INFO - PROCESS | 1664 | |2;jquery.html;337.94224078633874;383.1895540930932;384.95188101487315;390.11638638483856;393.13902553408036;20892.955352232402;21210.6818397724;21769.23807666732;22063.889680551594;22076.448088797788;17538.386156484525;21034.368968930958;21845.249599817646;22015.889920550413;22275.774832384213;5386.8922621547645;5616.606837521375;5635.098384258519;5641.069223578104;5648.830535083954;7589.127250366196;7953.72161974331;7973.083095444011;7985.600719963979;7989;7732.922670773277;7891.960540197291;7937.404694647896;7954.761357159266;7993.760187194364;7849.45053846231;7932.127465978738;7938.483998540077;7949.324307433875;7961.164077771838;7360.96319518399;7575;7595.278448547379;7595.734149304748;7600;22298.55402891933;29109.56335654967;30082.398352032837;30102;30213.69786302131;2566.3840678237275;2801.8178818376946;2847.202783220681;2858.399736055431;3198.296374797541;15600.453984110502;16015.519534413987;16291.674166516603;16569.66860662792;16777.993320400816;1897.79137205053;1997.790232025631;2018.7577490701062;2052.8504037738912;2062.989135323696;84440.72240749297;101550.49224753903;111532.4423377886;126486;132710;19630.803691963185;20337.186512539334;20454.897725511575;20460.897695511427;20492.487687807923;26698.5995210072;27195;29676.554851677243;31666.208344791103;31681;11313.490892909838;11398.487068081957;11420.029297509638;11461;11540.24988375753;9702.563384647709;9715;9719.951400242953;9730.610775568897;9812.509374531244;7909.090454597795;8132.308753755997;8818.823623527494;8835.381523293436;8871;3285.720713739359;3472.340255351475;3472.4964880092243;3474.8436320365645;3490.89527314181;908.4276905549593;912.7809325761903;925.7731855695398;952.0907533305676;1026.4046852825343;15412.383504659689;20318.796812031986;20498.282560110325;20639.380818575166;20752.44477091368;1010.7978404319166;1012.8531362952331;1037.5486663301488;1050.4537640426936;1081.291753901196;10865;11008.834867476995;11145.16411269158;11319.434028298552;11399.601013964471;651860;656245;656512;660543;662654;118896.40551797359;120685;122326;122684.38657806833;127201.3639931813;999.7200783780553;1079.8110330692098;1091.9617813376653;1092.1208427216009;1098.0337303173158;16113.355465781238;16422.589435264214;16796.748048779034;18708.7193692092;18736.81263187405;36797.81601091977;41439.1712165749;43291.35062973995;51978.74010629923;53631.731841340545;71330.93003605054;71375;76137;76292;76545.61727191329;6446.484281257582;8071.757847264592;8253.050899146676;8280.627371768405;8352.415330926777;9261.768455788659;13119.344032798514;13874.236916969663;14188.935829812599;14223.786643200567;10646.148308135176;11844;11960.282383057045;12000.279983201035;12057.397130143634;11548.538058477567;11556.711082223012;11725.76548469043;11912.940435297767;11920.225185362924;11753;11762.059035276987;11998.340091295062;12216.14486985938;12284.938575307066;6919.550229235084;8247.092819789937;8336.541490218096;8661.263792577702;8829.205371516595;11696.590619328283;11786.233894796811;11891.227070240408;11924.403779811148;12054.156209065632 16:33:14 INFO - PROCESS | 1664 | |3;mootools.html;1524.108396587996;1656.7183578791605;1685.1489997551234;2006.1674405121876;2066.5556905265366;8385.329173666107;8938.86591701125;9083.95458022709;9087.77280567986;9123.726288211355;8887.777805554864;9128.041555636662;9131;9158.816823663523;9166.862497062533;2582.9612555811677;2623.3966187776823;2628.8291261068066;2630.7632313091817;2631.2369412870244;2116.2593092417683;2600.7139214686367;2667.3465001074765;2676.4111895382985;2686.2209959111833;2213.5904857601313;2610.099515667092;2629.3032346428213;2642.1545105566224;2643.15419065899;2603.8307510011787;2615.8168928175037;2633.5259653262406;2634.3677517395877;2637.0770230419394;2637.446136311377;2660.467906418705;2671.893124275027;2675.531781938153;2678.424138810146;9096.681616143469;9216;9249.676261330822;9286.907130928672;9298.535073246378;5857.619254748428;6032.306284777219;6039.516838652898;6042.214512113441;6044.2746870375395;3696.9260614787822;3779.79211143387;3781.3004594149897;3799.4870692456443;3804.4673745675627;1066.2056767708107;1077.0629552289558;1080.8865069167703;1098.9725256868642;1118.558169523042;11636;11792.587259445878;11805.937465628049;11807.645770626896;11814.822777658343;5016.799328026911;5019.573336266385;5090.287359769562;5099.821506247264;5120.974395128001;10328.535215915303;10367.63713270032;10372.636957706594;10375.962403759564;10377.325473844176;5761.308642962871;5761.567882408836;5765.250517432707;5775.220345253337;5782.132680098019;4652.767361631973;4724.811007559659;4818.578519336804;4823.2282834746275;5209.218617207449;4120.244059725197;4139.710220284551;4199.811008504625;4223.028703398236;4244.405783190356;1370.3559327116238;1405.9297035148406;1407.5355132806149;1413.4840783114296;1415.3913817058558;1421.4172189402295;1440.7910852926477;1450.3690894460942;1462.8025216595836;1468.8163979502563;7689.923410722503;8076.394270429624;8529;8578.4424012439;8608.139186081342;533.5224973648562;538.7090970875762;545.1196317946487;548.3173449055951;548.4707257496535;6354.23749150105;6392.4886009119155;6394.808155755335;6412.2625898022325;6435.22777266731;11117.27737697047;11477.483513241925;11511.597094101833;11513.424328783527;11533.596324128615;10582.629607963685;10902.07332376741;11197.16021298406;11299.717507062225;11330.263532870336;8620.482771033758;8716.041235464094;8764.1674040966;8782.209601135928;8782.473051616924;8453.957730211556;8466.153384661484;8536.871946920925;8618.35362347839;8623.956880215559;8225.259726624632;10126.949365253127;10187.032231937954;10278.33190842593;10280.537375817956;8816.647334106821;10388.792224155626;10497.2127090467;10604.469776511298;10756.300840445347;6780.898286525805;7582.469227154046;7766.456348055582;7884.566348850868;7961.522308661499;8582.828343432971;8615.6984505542;8663.696770612998;8669.479831210148;8677.219050285505;7534.886976695464;7665.770026899202;7705.345045671181;7739.961300193463;7831;7466.477346585904;7525.924740752742;7537.510061845963;7608.733694320672;7619.961900190464;8042.517448953082;8165.918340816515;8209.425340226127;8213.507189568645;8215.973003374578;7124.928750712634;7324.414046876131;7334.339909408179;7374.1151061872915;7453.2546745325035;7445.627718614156;7472.327490525879;7497.475176737576;7510.136334321623;7521.135069466863 16:33:14 INFO - PROCESS | 1664 | |4;prototype.html;336.1763678986483;339.1216748621071;345.7735183454838;346.8023226760744;351.7625602718166;6641.402273795357;6653.135092437982;6658.367455091765;6668.56654317469;6693.364130407604;6588.176477940257;6605.900911486331;6633.800985970416;6635.104260924773;6635.502337324696;3758.32350176968;4754.6196304295745;4765.499622539635;4778.665493415462;4955.752212389375;4755.9286610700865;4764.2615394613895;4847.975760121194;4856.247281671346;4871.537203965618;4794.945112075356;4804.1352556539805;4811.206150985084;4815.807367705288;4821.035792841428;3704.833282502267;4834.613230941516;4849.539293767087;4863.100326439585;4879.048585525824;4096.037431203695;4213.241616509027;4214.157168566299;4218.2196293685765;4234.851780187679;6324.873502529969;6339.524535659844;6349.793354356067;6388.520860935402;6396.93603063968;5795.913061304083;5797.333306669702;5824.504917082053;5826.563007774434;5831.562632802556;6995.160580730294;6996.370326670624;6998.160220773489;7002.579845209304;7012.544184627983;4422.867313980586;4605.539446055401;4726.810927562893;4735.313379559979;4754.95245047552;7237.421006319481;7300.160481544637;7301.233370496075;7301.488895777297;7363.779086627356;3837.65461108496;4667.813287468532;4671.8364857229835;4679.789409476582;4689.202835517902;4258.339957306623;4409.030013397048;4740.502247264057;4753.47711751714;4756;5609;5620.85947851307;5622;5622.859428514238;5631.521320687705;6772.458203343819;6984.825379365455;6986.091808064919;6994.090768200102;7041.894371584431;1626.105641896952;1632.5347276026275;1646.8106167790618;1649.8597619202262;1650.7936507936508;1043.8434234864678;1043.95824167034;1057.0222544146634;1062.6546372428836;1067.6743593203992;6525.934740652533;6577.736890524421;6621.9006714899315;6624.668766561653;6713.832154196184;7314.45141614381;7391.963040184873;7400.555966642019;7416.184219735825;7443.702251909971;6750.729970801113;6757.8648427031185;6758.357955994173;6768.255491895888;6768.69540870662;3833.769973801581;3849.191669749384;3853.6531712146043;3863.864764733221;3868.941965870514;7269.8546029079125;7593.734219302298;7594.240575942472;7602.1257555380735;7641.617919104023;7381.704731810667;7425.1461081975185;7505.324520793155;7506.099268087864;7531.096268447822;6370.85324641569;7347.853042939111;7554.622268886534;7591.430642701819;7605.467617266738;7264.273572642694;7271.963640181765;7278.272172782891;7278.927210727825;7283.417326613964;6863.347981941708;7128.002079708741;7131.928680713334;7134.785956421316;7155.892661610184;7515.436342274242;7556.319931206218;7564.924350756422;7579.355754760686;7587.810304742426;4007.779572123561;4812.941152946346;4831.178699621002;4889.559939605452;4901.852944411673;7130.286971303036;7160.749373771893;7167.820804479929;7168.139823221247;7195.0646415967685;6711.362420570038;6961.930380696128;6996.720131194696;6997.47518936092;7006.649667516705;5698.772049117989;6869.969504574354;6938.375546200866;6947.270536593584;6965.686544105629;6815.727370905108;6829.112215411965;6841.486888483284;6853.9314606853295;6900.689468973807;6958.338957799001;7031.859362812613;7048.286409732187;7057.576545407292;7067.646617668993;6598.901016484852;6655.2346480153465;6802.761903333359;6860.176778786577;6915.343042410963 16:33:14 INFO - PROCESS | 1664 | |5;yui.html;715.3025799845149;720.4776537010667;767.6315368623061;777.1761932351708;827.2141465607671;15345.923270383646;15706.607334816636;15716.37134514618;15753.448629297976;15771.842281577181;14352.354144063516;14440;14461.276936153203;14468.638284042905;14475.638109047279;790.6955822008539;798.5328582779052;831.6881169561415;960.7646126698969;976.2434113561975;970.7621632699999;980.9656662016831;983.4689267795382;984.202795735453;985.7338518599973;1044.2742294105599;1046.6755305855172;1047.7275908263869;1048.2924026282267;1050.4485145298702;841.8484672758901;1026.420072658949;1037.1754455208127;1040.2198351236573;1042.1975079189058;770.1643716567474;771.0631582627135;774.9147593764682;776.0648418655563;780.6292011294589;15465.226738663134;15573.688526229525;15589.610259743484;15589.68820623581;15612.60968475786;12893.419796109198;12957.740845183043;13096.47614095435;13197;13666;10452.895471045269;11462.369569673665;11492.712682182928;11754.588589399329;11876.821847672292;3523.8766643167623;3563.1092226943265;3577.4276115821604;3633.854645814191;3821.3694740367805;69359.65320173367;82259;82855;86129;89858.30425087192;9721.513924303756;9837.213022958145;9847.113759761656;9892.109710126124;10059.647912323033;22351.441213969454;22451;22643.981020854102;22648.773512264997;22657.773422265895;7716.691332346643;7883.211678832071;7901.644426000844;8109.513429194267;8133.146019667968;4207.810648520824;4285.207236661227;4358.912821743547;4371.191329604034;4574.359589657451;3693.8153092345274;3717.0893131183034;3720.9441858372147;3724.7020238380865;3736.9162942746298;574.2764117212342;662.3045801908073;686.7596341280612;706.971721131149;732.2457868395561;550.5383207583872;589.9941000590024;617.1113596421139;624.2477814233837;624.656438958571;8128.227818357246;8413.495190288602;8497.787555311166;8589;8791.472511649194;520.6615699795087;583.5681595619211;614.8339948214046;627.4133685004535;651.6318280171782;3724.0875985383755;3902.687784977195;4031.596840315945;4081.5306239782212;4239.533651298355;90427;95114.52442737742;95518.52240738751;97306;97318.5134074325;85930;87936.56031719928;88032.5598372004;88941.55529222313;89342.55328723315;72673.63663181651;74340;76933;77147.61426192833;77513.83729244066;6602.372774586406;7892.960535197287;7981.760547183594;8134.715284964997;8434.95782521108;53914.46085539251;54579.72710136424;54793;55651;56605.71697141488;70756.64621677065;72376.63811680907;75595;77273;78674.42651147059;11151.442427878412;11259.718507037389;11442.427878606202;11540.076793856639;11910.880891190976;11973.16187866841;12216.572419965258;12238.632841014783;12249.632511024683;12505.687357816127;11708.180427370002;11906.940465297617;11913;12079.57721479744;12211.145219834527;11792.646220613395;11821.172517923662;11850.41812451915;12099.697507562381;12586.118971671895;11429.485673144562;11644.767104658029;11744.647660570197;11873.99071078968;11943.462544185706;10032.598696052077;10045.49772511386;10236.846447303147;10353.4305613192;10407.739806504898;9112.179903808688;9137.725868223964;9597.9040209597;10076.445795481026;10108.646197383056 16:33:14 INFO - PROCESS | 1664 | __end_tp_report 16:33:14 INFO - PROCESS | 1664 | __start_cc_report 16:33:14 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1071 16:33:14 INFO - PROCESS | 1664 | __end_cc_report 16:33:14 INFO - PROCESS | 1664 | __startTimestamp1462404794282__endTimestamp 16:33:14 INFO - PROCESS | 1664 | 16:33:14 INFO - PROCESS | 1664 | ------- Summary: start ------- 16:33:14 INFO - PROCESS | 1664 | Number of tests: 6 16:33:14 INFO - PROCESS | 1664 | 16:33:14 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5881.76 Median:6141.05 stddev:630.97 (10.3%) stddev-sans-first:606.08 16:33:14 INFO - PROCESS | 1664 | Values: 3561.2 3602.4 3619.0 3647.4 3699.7 5897.1 5915.1 5919.8 5947.5 5996.8 5920.7 5976.2 5979.5 5981.7 5984.7 5162.7 5259.7 5281.2 5340.9 5383.8 5386.7 5408.4 5418.9 5420.1 5421.4 5355.7 5412.9 5413.1 5444.5 5455.6 4757.4 5425.0 5468.5 5476.3 5476.5 5252.8 5345.8 5355.8 5372.2 5399.9 5857.9 5870.0 5899.5 5904.0 5925.2 5126.6 6151.9 6215.3 6326.2 6327.3 6314.5 6319.5 6323.4 6330.2 6345.4 6101.1 6104.5 6105.9 6117.4 6118.3 6283.7 6418.0 6480.3 6505.2 6517.2 5009.9 6218.7 6244.0 6260.6 6295.2 6224.8 6312.9 6323.8 6325.0 6333.7 6162.3 6180.5 6283.2 6287.0 6416.5 6269.7 6271.6 6275.9 6293.3 6337.2 4989.4 5063.9 5068.6 5079.3 5110.9 4461.0 4534.3 4558.2 4558.2 4600.3 6081.9 6091.1 6107.1 6120.2 6142.3 6158.8 6317.6 6334.1 6337.5 6354.1 6028.7 6054.5 6090.8 6103.6 6107.8 5556.4 5557.6 5559.6 5577.2 5587.2 6382.5 6487.6 6566.1 6579.3 6594.5 6265.2 6309.3 6316.8 6319.8 6493.4 6527.2 6543.4 6545.0 6562.6 6575.8 6148.6 6201.3 6254.7 6259.0 6266.0 6244.9 6294.2 6300.8 6306.2 6491.7 6141.0 6161.8 6213.1 6218.5 6223.1 5120.3 6148.4 6166.2 6170.5 6237.7 6195.7 6220.7 6227.0 6234.0 6236.6 5145.8 6205.6 6210.2 6215.3 6242.0 6057.8 6086.9 6117.2 6136.1 6172.0 16:33:14 INFO - PROCESS | 1664 | 16:33:14 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:64588.85 Median:19503.62 stddev:128373.63 (658.2%) stddev-sans-first:128643.57 16:33:14 INFO - PROCESS | 1664 | Values: 501.4 596.1 619.5 643.5 671.8 32011.2 32656.8 32700.8 41444.6 41797.2 44366.0 44759.8 44912.9 44990.1 45009.1 9634.7 9818.9 10054.2 10083.7 10102.0 8898.6 9988.2 9992.4 9998.6 10001.5 9937.3 9972.9 10007.3 10029.3 10100.7 10013.9 10093.0 10098.2 10103.7 10110.4 10011.5 10045.5 10154.2 10168.7 10170.0 42922.0 43374.9 43426.6 43463.0 43491.0 3409.3 3472.6 3554.6 3560.8 3571.1 28664.0 29608.6 29858.9 29891.4 30265.1 2798.7 2820.2 2832.4 2847.4 2895.4 476627.0 487330.6 488588.0 490163.0 491062.0 78766.2 79439.2 79512.6 79687.6 79724.6 86683.0 87613.6 87641.0 87711.0 88135.1 18724.3 18726.3 18885.3 18886.7 18891.7 14101.8 14198.0 14202.9 14212.6 14215.4 24897.6 25084.4 25309.6 25335.0 25376.0 8394.6 8759.1 10592.4 10669.3 10691.3 1016.7 1082.2 1126.0 1164.3 1180.3 29410.0 29479.6 29645.4 30139.2 30204.2 1135.1 1154.5 1165.9 1250.5 1272.1 12833.8 15379.9 15627.5 15767.7 15985.1 561986.2 562080.0 566502.0 566566.0 568248.0 53427.2 63398.0 63940.0 64034.0 64047.7 1460.6 1544.3 1557.6 1578.7 1603.8 30103.5 31686.7 31764.7 32041.4 32458.4 52435.5 52671.7 53092.1 53164.2 53172.7 377611.0 378867.0 379480.0 380038.0 380641.0 30872.9 35534.6 36603.8 36648.6 36760.3 44641.6 44678.8 45274.5 45453.0 45857.8 37015.4 37235.0 37248.6 37637.8 37692.6 36588.5 36698.6 37012.0 37342.6 37527.0 36821.4 38836.0 39083.9 39363.8 39461.2 33038.5 33306.5 33998.8 34017.8 34044.5 25429.4 25819.2 25832.7 26269.7 26286.1 16:33:14 INFO - PROCESS | 1664 | 16:33:14 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:37512.79 Median:26946.80 stddev:108648.09 (403.2%) stddev-sans-first:108917.02 16:33:14 INFO - PROCESS | 1664 | Values: 337.9 383.2 385.0 390.1 393.1 20893.0 21210.7 21769.2 22063.9 22076.4 17538.4 21034.4 21845.2 22015.9 22275.8 5386.9 5616.6 5635.1 5641.1 5648.8 7589.1 7953.7 7973.1 7985.6 7989.0 7732.9 7892.0 7937.4 7954.8 7993.8 7849.5 7932.1 7938.5 7949.3 7961.2 7361.0 7575.0 7595.3 7595.7 7600.0 22298.6 29109.6 30082.4 30102.0 30213.7 2566.4 2801.8 2847.2 2858.4 3198.3 15600.5 16015.5 16291.7 16569.7 16778.0 1897.8 1997.8 2018.8 2052.9 2063.0 84440.7 101550.5 111532.4 126486.0 132710.0 19630.8 20337.2 20454.9 20460.9 20492.5 26698.6 27195.0 29676.6 31666.2 31681.0 11313.5 11398.5 11420.0 11461.0 11540.2 9702.6 9715.0 9720.0 9730.6 9812.5 7909.1 8132.3 8818.8 8835.4 8871.0 3285.7 3472.3 3472.5 3474.8 3490.9 908.4 912.8 925.8 952.1 1026.4 15412.4 20318.8 20498.3 20639.4 20752.4 1010.8 1012.9 1037.5 1050.5 1081.3 10865.0 11008.8 11145.2 11319.4 11399.6 651860.0 656245.0 656512.0 660543.0 662654.0 118896.4 120685.0 122326.0 122684.4 127201.4 999.7 1079.8 1092.0 1092.1 1098.0 16113.4 16422.6 16796.7 18708.7 18736.8 36797.8 41439.2 43291.4 51978.7 53631.7 71330.9 71375.0 76137.0 76292.0 76545.6 6446.5 8071.8 8253.1 8280.6 8352.4 9261.8 13119.3 13874.2 14188.9 14223.8 10646.1 11844.0 11960.3 12000.3 12057.4 11548.5 11556.7 11725.8 11912.9 11920.2 11753.0 11762.1 11998.3 12216.1 12284.9 6919.6 8247.1 8336.5 8661.3 8829.2 11696.6 11786.2 11891.2 11924.4 12054.2 16:33:14 INFO - PROCESS | 1664 | 16:33:14 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:6312.87 Median:5110.40 stddev:3306.14 (64.7%) stddev-sans-first:3295.82 16:33:14 INFO - PROCESS | 1664 | Values: 1524.1 1656.7 1685.1 2006.2 2066.6 8385.3 8938.9 9084.0 9087.8 9123.7 8887.8 9128.0 9131.0 9158.8 9166.9 2583.0 2623.4 2628.8 2630.8 2631.2 2116.3 2600.7 2667.3 2676.4 2686.2 2213.6 2610.1 2629.3 2642.2 2643.2 2603.8 2615.8 2633.5 2634.4 2637.1 2637.4 2660.5 2671.9 2675.5 2678.4 9096.7 9216.0 9249.7 9286.9 9298.5 5857.6 6032.3 6039.5 6042.2 6044.3 3696.9 3779.8 3781.3 3799.5 3804.5 1066.2 1077.1 1080.9 1099.0 1118.6 11636.0 11792.6 11805.9 11807.6 11814.8 5016.8 5019.6 5090.3 5099.8 5121.0 10328.5 10367.6 10372.6 10376.0 10377.3 5761.3 5761.6 5765.3 5775.2 5782.1 4652.8 4724.8 4818.6 4823.2 5209.2 4120.2 4139.7 4199.8 4223.0 4244.4 1370.4 1405.9 1407.5 1413.5 1415.4 1421.4 1440.8 1450.4 1462.8 1468.8 7689.9 8076.4 8529.0 8578.4 8608.1 533.5 538.7 545.1 548.3 548.5 6354.2 6392.5 6394.8 6412.3 6435.2 11117.3 11477.5 11511.6 11513.4 11533.6 10582.6 10902.1 11197.2 11299.7 11330.3 8620.5 8716.0 8764.2 8782.2 8782.5 8454.0 8466.2 8536.9 8618.4 8624.0 8225.3 10126.9 10187.0 10278.3 10280.5 8816.6 10388.8 10497.2 10604.5 10756.3 6780.9 7582.5 7766.5 7884.6 7961.5 8582.8 8615.7 8663.7 8669.5 8677.2 7534.9 7665.8 7705.3 7740.0 7831.0 7466.5 7525.9 7537.5 7608.7 7620.0 8042.5 8165.9 8209.4 8213.5 8216.0 7124.9 7324.4 7334.3 7374.1 7453.3 7445.6 7472.3 7497.5 7510.1 7521.1 16:33:14 INFO - PROCESS | 1664 | 16:33:14 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:5753.74 Median:6635.30 stddev:1837.95 (27.7%) stddev-sans-first:1797.56 16:33:14 INFO - PROCESS | 1664 | Values: 336.2 339.1 345.8 346.8 351.8 6641.4 6653.1 6658.4 6668.6 6693.4 6588.2 6605.9 6633.8 6635.1 6635.5 3758.3 4754.6 4765.5 4778.7 4955.8 4755.9 4764.3 4848.0 4856.2 4871.5 4794.9 4804.1 4811.2 4815.8 4821.0 3704.8 4834.6 4849.5 4863.1 4879.0 4096.0 4213.2 4214.2 4218.2 4234.9 6324.9 6339.5 6349.8 6388.5 6396.9 5795.9 5797.3 5824.5 5826.6 5831.6 6995.2 6996.4 6998.2 7002.6 7012.5 4422.9 4605.5 4726.8 4735.3 4755.0 7237.4 7300.2 7301.2 7301.5 7363.8 3837.7 4667.8 4671.8 4679.8 4689.2 4258.3 4409.0 4740.5 4753.5 4756.0 5609.0 5620.9 5622.0 5622.9 5631.5 6772.5 6984.8 6986.1 6994.1 7041.9 1626.1 1632.5 1646.8 1649.9 1650.8 1043.8 1044.0 1057.0 1062.7 1067.7 6525.9 6577.7 6621.9 6624.7 6713.8 7314.5 7392.0 7400.6 7416.2 7443.7 6750.7 6757.9 6758.4 6768.3 6768.7 3833.8 3849.2 3853.7 3863.9 3868.9 7269.9 7593.7 7594.2 7602.1 7641.6 7381.7 7425.1 7505.3 7506.1 7531.1 6370.9 7347.9 7554.6 7591.4 7605.5 7264.3 7272.0 7278.3 7278.9 7283.4 6863.3 7128.0 7131.9 7134.8 7155.9 7515.4 7556.3 7564.9 7579.4 7587.8 4007.8 4812.9 4831.2 4889.6 4901.9 7130.3 7160.7 7167.8 7168.1 7195.1 6711.4 6961.9 6996.7 6997.5 7006.6 5698.8 6870.0 6938.4 6947.3 6965.7 6815.7 6829.1 6841.5 6853.9 6900.7 6958.3 7031.9 7048.3 7057.6 7067.6 6598.9 6655.2 6802.8 6860.2 6915.3 16:33:14 INFO - PROCESS | 1664 | 16:33:14 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:19449.33 Median:4472.78 stddev:27539.53 (615.7%) stddev-sans-first:27580.86 16:33:14 INFO - PROCESS | 1664 | Values: 715.3 720.5 767.6 777.2 827.2 15345.9 15706.6 15716.4 15753.4 15771.8 14352.4 14440.0 14461.3 14468.6 14475.6 790.7 798.5 831.7 960.8 976.2 970.8 981.0 983.5 984.2 985.7 1044.3 1046.7 1047.7 1048.3 1050.4 841.8 1026.4 1037.2 1040.2 1042.2 770.2 771.1 774.9 776.1 780.6 15465.2 15573.7 15589.6 15589.7 15612.6 12893.4 12957.7 13096.5 13197.0 13666.0 10452.9 11462.4 11492.7 11754.6 11876.8 3523.9 3563.1 3577.4 3633.9 3821.4 69359.7 82259.0 82855.0 86129.0 89858.3 9721.5 9837.2 9847.1 9892.1 10059.6 22351.4 22451.0 22644.0 22648.8 22657.8 7716.7 7883.2 7901.6 8109.5 8133.1 4207.8 4285.2 4358.9 4371.2 4574.4 3693.8 3717.1 3720.9 3724.7 3736.9 574.3 662.3 686.8 707.0 732.2 550.5 590.0 617.1 624.2 624.7 8128.2 8413.5 8497.8 8589.0 8791.5 520.7 583.6 614.8 627.4 651.6 3724.1 3902.7 4031.6 4081.5 4239.5 90427.0 95114.5 95518.5 97306.0 97318.5 85930.0 87936.6 88032.6 88941.6 89342.6 72673.6 74340.0 76933.0 77147.6 77513.8 6602.4 7893.0 7981.8 8134.7 8435.0 53914.5 54579.7 54793.0 55651.0 56605.7 70756.6 72376.6 75595.0 77273.0 78674.4 11151.4 11259.7 11442.4 11540.1 11910.9 11973.2 12216.6 12238.6 12249.6 12505.7 11708.2 11906.9 11913.0 12079.6 12211.1 11792.6 11821.2 11850.4 12099.7 12586.1 11429.5 11644.8 11744.6 11874.0 11943.5 10032.6 10045.5 10236.8 10353.4 10407.7 9112.2 9137.7 9597.9 10076.4 10108.6 16:33:14 INFO - PROCESS | 1664 | -------- Summary: end -------- 16:33:14 INFO - PROCESS | 1664 | 16:33:14 INFO - TEST-INFO | 1664: exit 0 16:33:14 INFO - TEST-OK | dromaeo_css | took 1111569ms 16:33:14 INFO - TEST-START | kraken 16:33:14 INFO - operating with platform_type : mac_ 16:33:14 INFO - Initialising browser for kraken test... 16:33:14 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmp_ykbIg/profile http://localhost:49275/getInfo.html) 16:33:16 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 16:33:16 INFO - PROCESS | 1672 | colorDepth:24 16:33:16 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 16:33:16 INFO - PROCESS | 1672 | __metrics 16:33:17 INFO - PROCESS | 1672 | 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" 16:33:17 INFO - PROCESS | 1672 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:33:17 INFO - TEST-INFO | 1672: exit 0 16:33:17 INFO - Browser initialized. 16:33:17 INFO - Running cycle 1/1 for kraken test... 16:33:17 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmp_ykbIg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:33:29 INFO - PROCESS | 1675 | RSS: Main: 157216768 16:33:29 INFO - PROCESS | 1675 | 16:33:31 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 16:33:32 INFO - PROCESS | 1675 | RSS: Main: 212848640 16:33:32 INFO - PROCESS | 1675 | 16:33:35 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 16:33:35 INFO - PROCESS | 1675 | RSS: Main: 223318016 16:33:35 INFO - PROCESS | 1675 | 16:33:38 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 16:33:39 INFO - PROCESS | 1675 | RSS: Main: 214405120 16:33:39 INFO - PROCESS | 1675 | 16:33:41 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 16:33:42 INFO - PROCESS | 1675 | RSS: Main: 215949312 16:33:42 INFO - PROCESS | 1675 | 16:33:44 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:33:44 INFO - PROCESS | 1675 | RSS: Main: 216850432 16:33:44 INFO - PROCESS | 1675 | 16:33:47 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 16:33:47 INFO - PROCESS | 1675 | RSS: Main: 216666112 16:33:47 INFO - PROCESS | 1675 | 16:33:50 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 16:33:51 INFO - PROCESS | 1675 | RSS: Main: 216784896 16:33:51 INFO - PROCESS | 1675 | 16:33:54 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 16:33:54 INFO - PROCESS | 1675 | RSS: Main: 217182208 16:33:54 INFO - PROCESS | 1675 | 16:33:56 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:33:56 INFO - PROCESS | 1675 | RSS: Main: 250007552 16:33:56 INFO - PROCESS | 1675 | 16:33:58 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:33:59 INFO - PROCESS | 1675 | RSS: Main: 250359808 16:33:59 INFO - PROCESS | 1675 | 16:34:01 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:34:02 INFO - PROCESS | 1675 | RSS: Main: 255913984 16:34:02 INFO - PROCESS | 1675 | 16:34:04 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:34:05 INFO - PROCESS | 1675 | RSS: Main: 257331200 16:34:05 INFO - PROCESS | 1675 | 16:34:07 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:34:08 INFO - PROCESS | 1675 | RSS: Main: 257347584 16:34:08 INFO - PROCESS | 1675 | 16:34:09 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 16:34:10 INFO - PROCESS | 1675 | RSS: Main: 320741376 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - PROCESS | 1675 | __start_tp_report 16:34:10 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 16:34:10 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 16:34:10 INFO - PROCESS | 1675 | |i|pagename|runs| 16:34:10 INFO - PROCESS | 1675 | |0;ai-astar;112;109;110;112;113;111;119;111;111;111 16:34:10 INFO - PROCESS | 1675 | |1;audio-beat-detection;158;119;142;156;119;136;138;120;159;135 16:34:10 INFO - PROCESS | 1675 | |2;audio-dft;181;184;183;186;186;182;185;185;184;182 16:34:10 INFO - PROCESS | 1675 | |3;audio-fft;73;79;96;157;82;58;59;67;147;82 16:34:10 INFO - PROCESS | 1675 | |4;audio-oscillator;87;93;127;82;77;84;113;75;78;110 16:34:10 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;89;86;89;85;88;84;88;86;88;86 16:34:10 INFO - PROCESS | 1675 | |6;imaging-darkroom;97;89;132;94;86;130;149;97;93;95 16:34:10 INFO - PROCESS | 1675 | |7;imaging-desaturate;86;85;87;84;87;84;85;87;87;86 16:34:10 INFO - PROCESS | 1675 | |8;json-parse-financial;54;59;59;57;58;56;58;68;52;50 16:34:10 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;63;61;69;75;70;66;65;64;64;66 16:34:10 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;81;84;81;80;76;66;78;92;102;110 16:34:10 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;117;119;117;125;115;127;120;116;126;127 16:34:10 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;134;123;134;132;132;124;167;174;130;132 16:34:10 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;66;67;65;66;67;70;62;94;64;65 16:34:10 INFO - PROCESS | 1675 | __end_tp_report 16:34:10 INFO - PROCESS | 1675 | __start_cc_report 16:34:10 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1216 16:34:10 INFO - PROCESS | 1675 | __end_cc_report 16:34:10 INFO - PROCESS | 1675 | __startTimestamp1462404850195__endTimestamp 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - PROCESS | 1675 | ------- Summary: start ------- 16:34:10 INFO - PROCESS | 1675 | Number of tests: 14 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:111.90 Median:111.50 stddev:2.73 (2.4%) stddev-sans-first:2.89 16:34:10 INFO - PROCESS | 1675 | Values: 112.0 109.0 110.0 112.0 113.0 111.0 119.0 111.0 111.0 111.0 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:140.00 stddev:15.77 (11.3%) stddev-sans-first:15.02 16:34:10 INFO - PROCESS | 1675 | Values: 158.0 119.0 142.0 156.0 119.0 136.0 138.0 120.0 159.0 135.0 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.50 stddev:1.75 (0.9%) stddev-sans-first:1.54 16:34:10 INFO - PROCESS | 1675 | Values: 181.0 184.0 183.0 186.0 186.0 182.0 185.0 185.0 184.0 182.0 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:90.00 Median:76.00 stddev:34.68 (45.6%) stddev-sans-first:36.24 16:34:10 INFO - PROCESS | 1675 | Values: 73.0 79.0 96.0 157.0 82.0 58.0 59.0 67.0 147.0 82.0 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:92.60 Median:80.00 stddev:17.91 (22.4%) stddev-sans-first:18.88 16:34:10 INFO - PROCESS | 1675 | Values: 87.0 93.0 127.0 82.0 77.0 84.0 113.0 75.0 78.0 110.0 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:88.00 stddev:1.73 (2.0%) stddev-sans-first:1.66 16:34:10 INFO - PROCESS | 1675 | Values: 89.0 86.0 89.0 85.0 88.0 84.0 88.0 86.0 88.0 86.0 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:106.20 Median:93.50 stddev:22.07 (23.6%) stddev-sans-first:23.16 16:34:10 INFO - PROCESS | 1675 | Values: 97.0 89.0 132.0 94.0 86.0 130.0 149.0 97.0 93.0 95.0 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:86.50 stddev:1.23 (1.4%) stddev-sans-first:1.30 16:34:10 INFO - PROCESS | 1675 | Values: 86.0 85.0 87.0 84.0 87.0 84.0 85.0 87.0 87.0 86.0 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:58.00 stddev:4.89 (8.4%) stddev-sans-first:5.05 16:34:10 INFO - PROCESS | 1675 | Values: 54.0 59.0 59.0 57.0 58.0 56.0 58.0 68.0 52.0 50.0 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:66.00 stddev:4.06 (6.1%) stddev-sans-first:4.12 16:34:10 INFO - PROCESS | 1675 | Values: 63.0 61.0 69.0 75.0 70.0 66.0 65.0 64.0 64.0 66.0 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:80.50 stddev:12.96 (16.1%) stddev-sans-first:13.67 16:34:10 INFO - PROCESS | 1675 | Values: 81.0 84.0 81.0 80.0 76.0 66.0 78.0 92.0 102.0 110.0 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:120.90 Median:122.50 stddev:4.84 (4.0%) stddev-sans-first:4.92 16:34:10 INFO - PROCESS | 1675 | Values: 117.0 119.0 117.0 125.0 115.0 127.0 120.0 116.0 126.0 127.0 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.20 Median:133.00 stddev:17.52 (13.2%) stddev-sans-first:18.51 16:34:10 INFO - PROCESS | 1675 | Values: 134.0 123.0 134.0 132.0 132.0 124.0 167.0 174.0 130.0 132.0 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:66.50 stddev:9.17 (13.8%) stddev-sans-first:9.68 16:34:10 INFO - PROCESS | 1675 | Values: 66.0 67.0 65.0 66.0 67.0 70.0 62.0 94.0 64.0 65.0 16:34:10 INFO - PROCESS | 1675 | -------- Summary: end -------- 16:34:10 INFO - PROCESS | 1675 | 16:34:10 INFO - TEST-INFO | 1675: exit 0 16:34:10 INFO - TEST-OK | kraken | took 56111ms 16:34:10 INFO - SUITE-END | took 1167s 16:34:11 INFO - Completed test suite (00:19:28) 16:34:11 INFO - javascript benchmark 16:34:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3561.21653236288, 3602.3875941090046, 3619.0, 3647.379945409281, 3699.6855267302276, 5897.085951677492, 5915.112733090039, 5919.822405327836, 5947.464728174464, 5996.790112346069, 5920.7039648017535, 5976.2230909981645, 5979.461848433651, 5981.671008094553, 5984.670843103628, 5162.664426812245, 5259.710715910633, 5281.155015197569, 5340.946590534103, 5383.838484845461, 5386.73066346683, 5408.432114627966, 5418.943306055321, 5420.1327787554, 5421.430749771276, 5355.732213389334, 5412.91880621791, 5413.052715774744, 5444.537214336786, 5455.563554915597, 4757.429108506966, 5425.0, 5468.535174510172, 5476.26070480488, 5476.534494567986, 5252.816151434719, 5345.759440825133, 5355.7857685692525, 5372.221027950925, 5399.946000539983, 5857.85355366115, 5870.0, 5899.46904778572, 5904.0, 5925.20009798676, 5126.61550383719, 6151.861905547444, 6215.316315205324, 6326.2092238470195, 6327.27236367819, 6314.463270622003, 6319.462845658079, 6323.430891219767, 6330.240371155444, 6345.397187267202, 6101.115338275969, 6104.511639068864, 6105.877882442326, 6117.418845209698, 6118.3269840317525, 6283.654399008007, 6418.0, 6480.287168411471, 6505.21937367519, 6517.152770139946, 5009.949900501021, 6218.657973811484, 6244.0321750128805, 6260.593061451083, 6295.213098362705, 6224.844378890564, 6312.873742525216, 6323.841903952346, 6325.0, 6333.714982825784, 6162.26052873658, 6180.47465965398, 6283.151774510383, 6287.0, 6416.518761092937, 6269.655168965751, 6271.560990730605, 6275.937240627627, 6293.30773614902, 6337.207849018872, 4989.351384320088, 5063.8987220255385, 5068.594512438996, 5079.288899554065, 5110.872228194251, 4461.0, 4534.342520334533, 4558.156741002925, 4558.202314594932, 4600.332951721982, 6081.939180608226, 6091.14723938649, 6107.053406721965, 6120.204373431515, 6142.324344322121, 6158.846028849314, 6317.6209427434505, 6334.144890439732, 6337.493000559944, 6354.142190804182, 6028.728707208186, 6054.455099041107, 6090.817275481742, 6103.6032657877095, 6107.795737660991, 5556.444355564411, 5557.638753481011, 5559.610827242135, 5577.247071645276, 5587.217789509472, 6382.521310901608, 6487.578307410004, 6566.146400967939, 6579.276279609239, 6594.538382313288, 6265.185525881606, 6309.2744334400295, 6316.810495685137, 6319.842003949938, 6493.383128602775, 6527.2167339919515, 6543.443807276435, 6545.0, 6562.606243625398, 6575.769848055295, 6148.569600128127, 6201.286852012076, 6254.655993920378, 6258.968705156627, 6266.0, 6244.937550624617, 6294.244690637152, 6300.842478938063, 6306.180196574416, 6491.707873145625, 6141.048137538689, 6161.815145545641, 6213.130161777264, 6218.533609979179, 6223.128761973418, 5120.334356533627, 6148.385161483816, 6166.229221347332, 6170.50635949132, 6237.719302631302, 6195.690215489298, 6220.657863817352, 6227.034809604337, 6234.0, 6236.6258024518675, 5145.768440420115, 6205.596636218631, 6210.161628180319, 6215.316315205233, 6241.96879015602, 6057.757689692363, 6086.934786412272, 6117.235345581802, 6136.059583474799, 6172.0], "unit": "score", "name": "dojo.html", "value": 5845.113777227825}, {"lowerIsBetter": false, "replicates": [501.4358846297915, 596.120721935146, 619.5198720991232, 643.4755674125589, 671.7984604618614, 32011.19972000701, 32656.83671581642, 32700.83649581752, 41444.58555414445, 41797.164056718924, 44366.0, 44759.77620111903, 44912.877178070565, 44990.10019799602, 45009.09981800362, 9634.747482827222, 9818.852717209229, 10054.195664346835, 10083.747906302327, 10102.040306170902, 8898.644054237822, 9988.200943924468, 9992.400455972629, 9998.600055997751, 10001.549930253155, 9937.304388692794, 9972.85583599526, 10007.29948903577, 10029.348092373972, 10100.696979090639, 10013.899861001406, 10093.0, 10098.192144628412, 10103.747406314827, 10110.39337639744, 10011.549480273405, 10045.54795034218, 10154.238432117621, 10168.745781355452, 10170.033846784543, 42922.0, 43374.91562710926, 43426.56573434257, 43463.0, 43491.0, 3409.2840503494294, 3472.6006509251506, 3554.591222009476, 3560.786352818839, 3571.1072231942017, 28664.0, 29608.55587166194, 29858.85070574655, 29891.40217195644, 30265.092047238617, 2798.720127987205, 2820.19624407043, 2832.4051949090717, 2847.359344147571, 2895.4353900989317, 476627.0, 487330.56334718456, 488587.99999999645, 490163.0, 491062.0, 78766.21233787588, 79439.20560794318, 79512.6024369886, 79687.60156199182, 79724.60137699274, 86683.0, 87613.56193219121, 87641.0, 87711.0, 88135.11864881398, 18724.25102995865, 18726.344577939708, 18885.339013134475, 18886.716699249522, 18891.677582569362, 14101.78847317291, 14198.0, 14202.928985355007, 14212.573622791333, 14215.36030878613, 24897.62653560198, 25084.372890677878, 25309.62035569468, 25334.98660053614, 25375.98496060137, 8394.58027098655, 8759.124087591317, 10592.364458132537, 10669.306495077795, 10691.30506517074, 1016.7000734783256, 1082.1721383141903, 1125.9887401125884, 1164.342146687119, 1180.3331117918349, 29410.0, 29479.557806632918, 29645.407091858044, 30139.246518836764, 30204.24489387783, 1135.1089394825187, 1154.520873837365, 1165.877582853805, 1250.5435516036582, 1272.1476610670873, 12833.762713883643, 15379.923100384427, 15627.531174064796, 15767.684646307009, 15985.120818355103, 561986.1900690552, 562080.0, 566502.0, 566566.0, 568248.0, 53427.19859202115, 63398.0, 63940.04089938655, 64034.0, 64047.67976160089, 1460.6494441334003, 1544.274191130167, 1557.6339560203344, 1578.6605879735912, 1603.8476344747464, 30103.496614736472, 31686.683133169296, 31764.682353176173, 32041.35917281688, 32458.35083298368, 52435.47564524306, 52671.73664131655, 53092.141775037686, 53164.20253696275, 53172.73413632907, 377611.0, 378867.0, 379480.0, 380038.0, 380641.0, 30872.91944781922, 35534.64465355417, 36603.816980914926, 36648.63351366452, 36760.26479470342, 44641.55358446374, 44678.776606116764, 45274.54725452703, 45453.0, 45857.77071114623, 37015.44476832796, 37235.0, 37248.62751372452, 37637.811810941865, 37692.62307376891, 36588.451173232956, 36698.63301367059, 37012.0, 37342.626573735004, 37527.0, 36821.44767828538, 38836.0, 39083.89619090068, 39363.80318098391, 39461.2107757849, 33038.5044224332, 33306.500402494465, 33998.8300058508, 34017.80937667269, 34044.489332659534, 25429.364265893502, 25819.225423237334, 25832.741672583034, 26269.73730262673, 26286.079987200355], "unit": "score", "name": "ext.html", "value": 21045.00036726704}, {"lowerIsBetter": false, "replicates": [337.94224078633874, 383.1895540930932, 384.95188101487315, 390.11638638483856, 393.13902553408036, 20892.955352232402, 21210.6818397724, 21769.23807666732, 22063.889680551594, 22076.448088797788, 17538.386156484525, 21034.368968930958, 21845.249599817646, 22015.889920550413, 22275.774832384213, 5386.8922621547645, 5616.606837521375, 5635.098384258519, 5641.069223578104, 5648.830535083954, 7589.127250366196, 7953.72161974331, 7973.083095444011, 7985.600719963979, 7989.0, 7732.922670773277, 7891.960540197291, 7937.404694647896, 7954.761357159266, 7993.760187194364, 7849.45053846231, 7932.127465978738, 7938.483998540077, 7949.324307433875, 7961.164077771838, 7360.96319518399, 7575.0, 7595.278448547379, 7595.734149304748, 7600.0, 22298.55402891933, 29109.56335654967, 30082.398352032837, 30102.0, 30213.69786302131, 2566.3840678237275, 2801.8178818376946, 2847.202783220681, 2858.399736055431, 3198.296374797541, 15600.453984110502, 16015.519534413987, 16291.674166516603, 16569.66860662792, 16777.993320400816, 1897.79137205053, 1997.790232025631, 2018.7577490701062, 2052.8504037738912, 2062.989135323696, 84440.72240749297, 101550.49224753903, 111532.4423377886, 126486.0, 132710.0, 19630.803691963185, 20337.186512539334, 20454.897725511575, 20460.897695511427, 20492.487687807923, 26698.5995210072, 27195.0, 29676.554851677243, 31666.208344791103, 31681.0, 11313.490892909838, 11398.487068081957, 11420.029297509638, 11461.0, 11540.24988375753, 9702.563384647709, 9715.0, 9719.951400242953, 9730.610775568897, 9812.509374531244, 7909.090454597795, 8132.308753755997, 8818.823623527494, 8835.381523293436, 8871.0, 3285.720713739359, 3472.340255351475, 3472.4964880092243, 3474.8436320365645, 3490.89527314181, 908.4276905549593, 912.7809325761903, 925.7731855695398, 952.0907533305676, 1026.4046852825343, 15412.383504659689, 20318.796812031986, 20498.282560110325, 20639.380818575166, 20752.44477091368, 1010.7978404319166, 1012.8531362952331, 1037.5486663301488, 1050.4537640426936, 1081.291753901196, 10865.0, 11008.834867476995, 11145.16411269158, 11319.434028298552, 11399.601013964471, 651860.0, 656245.0, 656512.0, 660543.0, 662654.0, 118896.40551797359, 120685.0, 122326.0, 122684.38657806833, 127201.3639931813, 999.7200783780553, 1079.8110330692098, 1091.9617813376653, 1092.1208427216009, 1098.0337303173158, 16113.355465781238, 16422.589435264214, 16796.748048779034, 18708.7193692092, 18736.81263187405, 36797.81601091977, 41439.1712165749, 43291.35062973995, 51978.74010629923, 53631.731841340545, 71330.93003605054, 71375.0, 76137.0, 76292.0, 76545.61727191329, 6446.484281257582, 8071.757847264592, 8253.050899146676, 8280.627371768405, 8352.415330926777, 9261.768455788659, 13119.344032798514, 13874.236916969663, 14188.935829812599, 14223.786643200567, 10646.148308135176, 11844.0, 11960.282383057045, 12000.279983201035, 12057.397130143634, 11548.538058477567, 11556.711082223012, 11725.76548469043, 11912.940435297767, 11920.225185362924, 11753.0, 11762.059035276987, 11998.340091295062, 12216.14486985938, 12284.938575307066, 6919.550229235084, 8247.092819789937, 8336.541490218096, 8661.263792577702, 8829.205371516595, 11696.590619328283, 11786.233894796811, 11891.227070240408, 11924.403779811148, 12054.156209065632], "unit": "score", "name": "jquery.html", "value": 11047.471780556805}, {"lowerIsBetter": false, "replicates": [1524.108396587996, 1656.7183578791605, 1685.1489997551234, 2006.1674405121876, 2066.5556905265366, 8385.329173666107, 8938.86591701125, 9083.95458022709, 9087.77280567986, 9123.726288211355, 8887.777805554864, 9128.041555636662, 9131.0, 9158.816823663523, 9166.862497062533, 2582.9612555811677, 2623.3966187776823, 2628.8291261068066, 2630.7632313091817, 2631.2369412870244, 2116.2593092417683, 2600.7139214686367, 2667.3465001074765, 2676.4111895382985, 2686.2209959111833, 2213.5904857601313, 2610.099515667092, 2629.3032346428213, 2642.1545105566224, 2643.15419065899, 2603.8307510011787, 2615.8168928175037, 2633.5259653262406, 2634.3677517395877, 2637.0770230419394, 2637.446136311377, 2660.467906418705, 2671.893124275027, 2675.531781938153, 2678.424138810146, 9096.681616143469, 9216.0, 9249.676261330822, 9286.907130928672, 9298.535073246378, 5857.619254748428, 6032.306284777219, 6039.516838652898, 6042.214512113441, 6044.2746870375395, 3696.9260614787822, 3779.79211143387, 3781.3004594149897, 3799.4870692456443, 3804.4673745675627, 1066.2056767708107, 1077.0629552289558, 1080.8865069167703, 1098.9725256868642, 1118.558169523042, 11636.0, 11792.587259445878, 11805.937465628049, 11807.645770626896, 11814.822777658343, 5016.799328026911, 5019.573336266385, 5090.287359769562, 5099.821506247264, 5120.974395128001, 10328.535215915303, 10367.63713270032, 10372.636957706594, 10375.962403759564, 10377.325473844176, 5761.308642962871, 5761.567882408836, 5765.250517432707, 5775.220345253337, 5782.132680098019, 4652.767361631973, 4724.811007559659, 4818.578519336804, 4823.2282834746275, 5209.218617207449, 4120.244059725197, 4139.710220284551, 4199.811008504625, 4223.028703398236, 4244.405783190356, 1370.3559327116238, 1405.9297035148406, 1407.5355132806149, 1413.4840783114296, 1415.3913817058558, 1421.4172189402295, 1440.7910852926477, 1450.3690894460942, 1462.8025216595836, 1468.8163979502563, 7689.923410722503, 8076.394270429624, 8529.0, 8578.4424012439, 8608.139186081342, 533.5224973648562, 538.7090970875762, 545.1196317946487, 548.3173449055951, 548.4707257496535, 6354.23749150105, 6392.4886009119155, 6394.808155755335, 6412.2625898022325, 6435.22777266731, 11117.27737697047, 11477.483513241925, 11511.597094101833, 11513.424328783527, 11533.596324128615, 10582.629607963685, 10902.07332376741, 11197.16021298406, 11299.717507062225, 11330.263532870336, 8620.482771033758, 8716.041235464094, 8764.1674040966, 8782.209601135928, 8782.473051616924, 8453.957730211556, 8466.153384661484, 8536.871946920925, 8618.35362347839, 8623.956880215559, 8225.259726624632, 10126.949365253127, 10187.032231937954, 10278.33190842593, 10280.537375817956, 8816.647334106821, 10388.792224155626, 10497.2127090467, 10604.469776511298, 10756.300840445347, 6780.898286525805, 7582.469227154046, 7766.456348055582, 7884.566348850868, 7961.522308661499, 8582.828343432971, 8615.6984505542, 8663.696770612998, 8669.479831210148, 8677.219050285505, 7534.886976695464, 7665.770026899202, 7705.345045671181, 7739.961300193463, 7831.0, 7466.477346585904, 7525.924740752742, 7537.510061845963, 7608.733694320672, 7619.961900190464, 8042.517448953082, 8165.918340816515, 8209.425340226127, 8213.507189568645, 8215.973003374578, 7124.928750712634, 7324.414046876131, 7334.339909408179, 7374.1151061872915, 7453.2546745325035, 7445.627718614156, 7472.327490525879, 7497.475176737576, 7510.136334321623, 7521.135069466863], "unit": "score", "name": "mootools.html", "value": 5086.480780411539}, {"lowerIsBetter": false, "replicates": [336.1763678986483, 339.1216748621071, 345.7735183454838, 346.8023226760744, 351.7625602718166, 6641.402273795357, 6653.135092437982, 6658.367455091765, 6668.56654317469, 6693.364130407604, 6588.176477940257, 6605.900911486331, 6633.800985970416, 6635.104260924773, 6635.502337324696, 3758.32350176968, 4754.6196304295745, 4765.499622539635, 4778.665493415462, 4955.752212389375, 4755.9286610700865, 4764.2615394613895, 4847.975760121194, 4856.247281671346, 4871.537203965618, 4794.945112075356, 4804.1352556539805, 4811.206150985084, 4815.807367705288, 4821.035792841428, 3704.833282502267, 4834.613230941516, 4849.539293767087, 4863.100326439585, 4879.048585525824, 4096.037431203695, 4213.241616509027, 4214.157168566299, 4218.2196293685765, 4234.851780187679, 6324.873502529969, 6339.524535659844, 6349.793354356067, 6388.520860935402, 6396.93603063968, 5795.913061304083, 5797.333306669702, 5824.504917082053, 5826.563007774434, 5831.562632802556, 6995.160580730294, 6996.370326670624, 6998.160220773489, 7002.579845209304, 7012.544184627983, 4422.867313980586, 4605.539446055401, 4726.810927562893, 4735.313379559979, 4754.95245047552, 7237.421006319481, 7300.160481544637, 7301.233370496075, 7301.488895777297, 7363.779086627356, 3837.65461108496, 4667.813287468532, 4671.8364857229835, 4679.789409476582, 4689.202835517902, 4258.339957306623, 4409.030013397048, 4740.502247264057, 4753.47711751714, 4756.0, 5609.0, 5620.85947851307, 5622.0, 5622.859428514238, 5631.521320687705, 6772.458203343819, 6984.825379365455, 6986.091808064919, 6994.090768200102, 7041.894371584431, 1626.105641896952, 1632.5347276026275, 1646.8106167790618, 1649.8597619202262, 1650.7936507936508, 1043.8434234864678, 1043.95824167034, 1057.0222544146634, 1062.6546372428836, 1067.6743593203992, 6525.934740652533, 6577.736890524421, 6621.9006714899315, 6624.668766561653, 6713.832154196184, 7314.45141614381, 7391.963040184873, 7400.555966642019, 7416.184219735825, 7443.702251909971, 6750.729970801113, 6757.8648427031185, 6758.357955994173, 6768.255491895888, 6768.69540870662, 3833.769973801581, 3849.191669749384, 3853.6531712146043, 3863.864764733221, 3868.941965870514, 7269.8546029079125, 7593.734219302298, 7594.240575942472, 7602.1257555380735, 7641.617919104023, 7381.704731810667, 7425.1461081975185, 7505.324520793155, 7506.099268087864, 7531.096268447822, 6370.85324641569, 7347.853042939111, 7554.622268886534, 7591.430642701819, 7605.467617266738, 7264.273572642694, 7271.963640181765, 7278.272172782891, 7278.927210727825, 7283.417326613964, 6863.347981941708, 7128.002079708741, 7131.928680713334, 7134.785956421316, 7155.892661610184, 7515.436342274242, 7556.319931206218, 7564.924350756422, 7579.355754760686, 7587.810304742426, 4007.779572123561, 4812.941152946346, 4831.178699621002, 4889.559939605452, 4901.852944411673, 7130.286971303036, 7160.749373771893, 7167.820804479929, 7168.139823221247, 7195.0646415967685, 6711.362420570038, 6961.930380696128, 6996.720131194696, 6997.47518936092, 7006.649667516705, 5698.772049117989, 6869.969504574354, 6938.375546200866, 6947.270536593584, 6965.686544105629, 6815.727370905108, 6829.112215411965, 6841.486888483284, 6853.9314606853295, 6900.689468973807, 6958.338957799001, 7031.859362812613, 7048.286409732187, 7057.576545407292, 7067.646617668993, 6598.901016484852, 6655.2346480153465, 6802.761903333359, 6860.176778786577, 6915.343042410963], "unit": "score", "name": "prototype.html", "value": 5142.72549344432}, {"lowerIsBetter": false, "replicates": [715.3025799845149, 720.4776537010667, 767.6315368623061, 777.1761932351708, 827.2141465607671, 15345.923270383646, 15706.607334816636, 15716.37134514618, 15753.448629297976, 15771.842281577181, 14352.354144063516, 14440.0, 14461.276936153203, 14468.638284042905, 14475.638109047279, 790.6955822008539, 798.5328582779052, 831.6881169561415, 960.7646126698969, 976.2434113561975, 970.7621632699999, 980.9656662016831, 983.4689267795382, 984.202795735453, 985.7338518599973, 1044.2742294105599, 1046.6755305855172, 1047.7275908263869, 1048.2924026282267, 1050.4485145298702, 841.8484672758901, 1026.420072658949, 1037.1754455208127, 1040.2198351236573, 1042.1975079189058, 770.1643716567474, 771.0631582627135, 774.9147593764682, 776.0648418655563, 780.6292011294589, 15465.226738663134, 15573.688526229525, 15589.610259743484, 15589.68820623581, 15612.60968475786, 12893.419796109198, 12957.740845183043, 13096.47614095435, 13197.0, 13666.0, 10452.895471045269, 11462.369569673665, 11492.712682182928, 11754.588589399329, 11876.821847672292, 3523.8766643167623, 3563.1092226943265, 3577.4276115821604, 3633.854645814191, 3821.3694740367805, 69359.65320173367, 82259.0, 82855.0, 86129.0, 89858.30425087192, 9721.513924303756, 9837.213022958145, 9847.113759761656, 9892.109710126124, 10059.647912323033, 22351.441213969454, 22451.0, 22643.981020854102, 22648.773512264997, 22657.773422265895, 7716.691332346643, 7883.211678832071, 7901.644426000844, 8109.513429194267, 8133.146019667968, 4207.810648520824, 4285.207236661227, 4358.912821743547, 4371.191329604034, 4574.359589657451, 3693.8153092345274, 3717.0893131183034, 3720.9441858372147, 3724.7020238380865, 3736.9162942746298, 574.2764117212342, 662.3045801908073, 686.7596341280612, 706.971721131149, 732.2457868395561, 550.5383207583872, 589.9941000590024, 617.1113596421139, 624.2477814233837, 624.656438958571, 8128.227818357246, 8413.495190288602, 8497.787555311166, 8589.0, 8791.472511649194, 520.6615699795087, 583.5681595619211, 614.8339948214046, 627.4133685004535, 651.6318280171782, 3724.0875985383755, 3902.687784977195, 4031.596840315945, 4081.5306239782212, 4239.533651298355, 90427.0, 95114.52442737742, 95518.52240738751, 97306.0, 97318.5134074325, 85930.0, 87936.56031719928, 88032.5598372004, 88941.55529222313, 89342.55328723315, 72673.63663181651, 74340.0, 76933.0, 77147.61426192833, 77513.83729244066, 6602.372774586406, 7892.960535197287, 7981.760547183594, 8134.715284964997, 8434.95782521108, 53914.46085539251, 54579.72710136424, 54793.0, 55651.0, 56605.71697141488, 70756.64621677065, 72376.63811680907, 75595.0, 77273.0, 78674.42651147059, 11151.442427878412, 11259.718507037389, 11442.427878606202, 11540.076793856639, 11910.880891190976, 11973.16187866841, 12216.572419965258, 12238.632841014783, 12249.632511024683, 12505.687357816127, 11708.180427370002, 11906.940465297617, 11913.0, 12079.57721479744, 12211.145219834527, 11792.646220613395, 11821.172517923662, 11850.41812451915, 12099.697507562381, 12586.118971671895, 11429.485673144562, 11644.767104658029, 11744.647660570197, 11873.99071078968, 11943.462544185706, 10032.598696052077, 10045.49772511386, 10236.846447303147, 10353.4305613192, 10407.739806504898, 9112.179903808688, 9137.725868223964, 9597.9040209597, 10076.445795481026, 10108.646197383056], "unit": "score", "name": "yui.html", "value": 7249.7768358781395}], "name": "dromaeo_css", "value": 7977.428521492197}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 109.0, 110.0, 112.0, 113.0, 111.0, 119.0, 111.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 111.9}, {"lowerIsBetter": true, "replicates": [158.0, 119.0, 142.0, 156.0, 119.0, 136.0, 138.0, 120.0, 159.0, 135.0], "unit": "score", "name": "audio-beat-detection", "value": 138.2}, {"lowerIsBetter": true, "replicates": [181.0, 184.0, 183.0, 186.0, 186.0, 182.0, 185.0, 185.0, 184.0, 182.0], "unit": "score", "name": "audio-dft", "value": 183.8}, {"lowerIsBetter": true, "replicates": [73.0, 79.0, 96.0, 157.0, 82.0, 58.0, 59.0, 67.0, 147.0, 82.0], "unit": "score", "name": "audio-fft", "value": 90.0}, {"lowerIsBetter": true, "replicates": [87.0, 93.0, 127.0, 82.0, 77.0, 84.0, 113.0, 75.0, 78.0, 110.0], "unit": "score", "name": "audio-oscillator", "value": 92.6}, {"lowerIsBetter": true, "replicates": [89.0, 86.0, 89.0, 85.0, 88.0, 84.0, 88.0, 86.0, 88.0, 86.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 86.9}, {"lowerIsBetter": true, "replicates": [97.0, 89.0, 132.0, 94.0, 86.0, 130.0, 149.0, 97.0, 93.0, 95.0], "unit": "score", "name": "imaging-darkroom", "value": 106.2}, {"lowerIsBetter": true, "replicates": [86.0, 85.0, 87.0, 84.0, 87.0, 84.0, 85.0, 87.0, 87.0, 86.0], "unit": "score", "name": "imaging-desaturate", "value": 85.8}, {"lowerIsBetter": true, "replicates": [54.0, 59.0, 59.0, 57.0, 58.0, 56.0, 58.0, 68.0, 52.0, 50.0], "unit": "score", "name": "json-parse-financial", "value": 57.1}, {"lowerIsBetter": true, "replicates": [63.0, 61.0, 69.0, 75.0, 70.0, 66.0, 65.0, 64.0, 64.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.3}, {"lowerIsBetter": true, "replicates": [81.0, 84.0, 81.0, 80.0, 76.0, 66.0, 78.0, 92.0, 102.0, 110.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.0}, {"lowerIsBetter": true, "replicates": [117.0, 119.0, 117.0, 125.0, 115.0, 127.0, 120.0, 116.0, 126.0, 127.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 120.9}, {"lowerIsBetter": true, "replicates": [134.0, 123.0, 134.0, 132.0, 132.0, 124.0, 167.0, 174.0, 130.0, 132.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 138.2}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 65.0, 66.0, 67.0, 70.0, 62.0, 94.0, 64.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.6}], "name": "kraken", "value": 1431.5}]} 16:34:11 INFO - Return code: 0 16:34:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:34:11 INFO - # TBPL SUCCESS # 16:34:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:34:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:34:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:34:11 INFO - 2016-05-04 16:34:11.532 screenresolution[1679:10003] starting screenresolution argv=screenresolution get 16:34:11 INFO - 2016-05-04 16:34:11.534 screenresolution[1679:10003] Display 0: 1600x1200x32@60 16:34:11 INFO - 2016-05-04 16:34:11.540 screenresolution[1680:10006] starting screenresolution argv=screenresolution list 16:34:11 INFO - Available Modes on Display 0 16:34:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:34:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:34:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:34:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:34:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:34:11 INFO - Intel Iris: 16:34:11 INFO - Chipset Model: Intel Iris 16:34:11 INFO - Type: GPU 16:34:11 INFO - Bus: Built-In 16:34:11 INFO - VRAM (Dynamic, Max): 1536 MB 16:34:11 INFO - Vendor: Intel (0x8086) 16:34:11 INFO - Device ID: 0x0a2e 16:34:11 INFO - Revision ID: 0x0009 16:34:11 INFO - Displays: 16:34:11 INFO - Display: 16:34:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:34:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:34:11 INFO - Display Serial Number: 1600x1200 60 16:34:11 INFO - Main Display: Yes 16:34:11 INFO - Mirror: Off 16:34:11 INFO - Online: Yes 16:34:11 INFO - Rotation: Supported 16:34:11 INFO - Return code: 0 16:34:11 INFO - Running post-action listener: _resource_record_post_action 16:34:11 INFO - Running post-run listener: _resource_record_post_run 16:34:12 INFO - Total resource usage - Wall time: 1192s; CPU: 53.0%; Read bytes: 37914112; Write bytes: 508962304; Read time: 391; Write time: 1719 16:34:12 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 198619648; Write bytes: 197834240; Read time: 17969; Write time: 409 16:34:12 INFO - run-tests - Wall time: 1170s; CPU: 52.0%; Read bytes: 36419072; Write bytes: 301170688; Read time: 373; Write time: 1277 16:34:12 INFO - Running post-run listener: _upload_blobber_files 16:34:12 INFO - Blob upload gear active. 16:34:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:34:12 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 16:34:12 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'] 16:34:12 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 16:34:13 INFO - (blobuploader) - INFO - Open directory for files ... 16:34:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:34:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:34:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:34:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:34:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:34:14 INFO - (blobuploader) - INFO - Done attempting. 16:34:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:34:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:34:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:34:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:34:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:34:15 INFO - (blobuploader) - INFO - Done attempting. 16:34:15 INFO - (blobuploader) - INFO - Iteration through files over. 16:34:15 INFO - Return code: 0 16:34:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:34:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:34:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fab3358eb5ccc4c01bd990fcad2dfd6e7194a501936fa8210ce9ff70cf593fe1923ce85767e3a4cbe1172a2bc9e4831ddfd6507f4854f4a9b8a123f3e8b68ff1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f368f2a7cfc02c3ba0cdbdcf616f6d35aa1aaedf62df75b8b840e5465579937fd73bfa8118c22a707f1655e57fa3a6de8901c145b58971c1481826844e10e3e7"} 16:34:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:34:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:34:15 INFO - Contents: 16:34:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fab3358eb5ccc4c01bd990fcad2dfd6e7194a501936fa8210ce9ff70cf593fe1923ce85767e3a4cbe1172a2bc9e4831ddfd6507f4854f4a9b8a123f3e8b68ff1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f368f2a7cfc02c3ba0cdbdcf616f6d35aa1aaedf62df75b8b840e5465579937fd73bfa8118c22a707f1655e57fa3a6de8901c145b58971c1481826844e10e3e7"} 16:34:15 INFO - Running post-run listener: copy_logs_to_upload_dir 16:34:15 INFO - Copying logs to upload dir... 16:34:15 INFO - mkdir: /builds/slave/test/build/upload/logs 16:34:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.585444 ========= master_lag: 4.99 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-05-04 16:34:20.400208) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-05-04 16:34:20.408809) ========= 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.0nZdQxsKtv/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.3GWjnbhFVk/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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/fab3358eb5ccc4c01bd990fcad2dfd6e7194a501936fa8210ce9ff70cf593fe1923ce85767e3a4cbe1172a2bc9e4831ddfd6507f4854f4a9b8a123f3e8b68ff1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f368f2a7cfc02c3ba0cdbdcf616f6d35aa1aaedf62df75b8b840e5465579937fd73bfa8118c22a707f1655e57fa3a6de8901c145b58971c1481826844e10e3e7"} build_url:https://queue.taskcluster.net/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011170 build_url: 'https://queue.taskcluster.net/v1/task/av4-CZezQsyGgGHLgPO8Dg/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fab3358eb5ccc4c01bd990fcad2dfd6e7194a501936fa8210ce9ff70cf593fe1923ce85767e3a4cbe1172a2bc9e4831ddfd6507f4854f4a9b8a123f3e8b68ff1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f368f2a7cfc02c3ba0cdbdcf616f6d35aa1aaedf62df75b8b840e5465579937fd73bfa8118c22a707f1655e57fa3a6de8901c145b58971c1481826844e10e3e7"}' ========= master_lag: 2.90 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-05-04 16:34:23.323577) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-05-04 16:34:23.323888) ========= 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.0nZdQxsKtv/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.3GWjnbhFVk/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.005179 ========= master_lag: 5.34 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-05-04 16:34:28.673862) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-04 16:34:28.674162) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-04 16:34:28.870744) ========= ========= Total master_lag: 28.93 =========