builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0178 starttime: 1468475776.2 results: success (0) buildid: 20160713154642 builduid: 0219f93640ac4a39933847396c8d0f21 revision: 84c59d136a9e54be69571b75a19e8a8ccff7b8a1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:16.200445) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:16.200960) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:16.201340) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:16.256359) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:16.256679) ========= 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.YIIkTArXaO/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.hEgJoD2e7w/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-13 22:56:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.0M=0.001s 2016-07-13 22:56:16 (15.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.719903 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:17.015118) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:17.015564) ========= 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.YIIkTArXaO/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.hEgJoD2e7w/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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.027501 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:17.066849) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 22:56:17.067314) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 84c59d136a9e54be69571b75a19e8a8ccff7b8a1 --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 84c59d136a9e54be69571b75a19e8a8ccff7b8a1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YIIkTArXaO/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.hEgJoD2e7w/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-13 22:56:17,141 truncating revision to first 12 chars 2016-07-13 22:56:17,141 Setting DEBUG logging. 2016-07-13 22:56:17,141 attempt 1/10 2016-07-13 22:56:17,142 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/84c59d136a9e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 22:56:18,553 unpacking tar archive at: mozilla-beta-84c59d136a9e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.671739 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 22:56:18.764064) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:18.764442) ========= script_repo_revision: 84c59d136a9e54be69571b75a19e8a8ccff7b8a1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:18.765397) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:18.765758) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:18.784278) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-07-13 22:56:18.784703) ========= /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.YIIkTArXaO/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.hEgJoD2e7w/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 22:56:18 INFO - MultiFileLogger online at 20160713 22:56:18 in /builds/slave/test 22:56:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 22:56:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:56:18 INFO - {'append_to_log': False, 22:56:18 INFO - 'base_work_dir': '/builds/slave/test', 22:56:18 INFO - 'blob_upload_branch': 'Mozilla-Beta', 22:56:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:56:18 INFO - 'branch': 'Mozilla-Beta', 22:56:18 INFO - 'buildbot_json_path': 'buildprops.json', 22:56:18 INFO - 'config_files': ('talos/mac_config.py',), 22:56:18 INFO - 'default_actions': ('clobber', 22:56:18 INFO - 'read-buildbot-config', 22:56:18 INFO - 'download-and-extract', 22:56:18 INFO - 'populate-webroot', 22:56:18 INFO - 'create-virtualenv', 22:56:18 INFO - 'install', 22:56:18 INFO - 'run-tests'), 22:56:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:56:18 INFO - 'download_minidump_stackwalk': True, 22:56:18 INFO - 'download_symbols': 'ondemand', 22:56:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:56:18 INFO - 'tooltool.py': '/tools/tooltool.py', 22:56:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:56:18 INFO - '/tools/misc-python/virtualenv.py')}, 22:56:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:56:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:56:18 INFO - 'installer_path': 'installer.exe', 22:56:18 INFO - 'log_level': 'info', 22:56:18 INFO - 'log_name': 'talos', 22:56:18 INFO - 'log_to_console': True, 22:56:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:56:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:56:18 INFO - 'opt_config_files': (), 22:56:18 INFO - 'pip_index': False, 22:56:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:56:18 INFO - 'cmd': ('bash', 22:56:18 INFO - '-c', 22:56:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:56:18 INFO - 'enabled': True, 22:56:18 INFO - 'halt_on_failure': False, 22:56:18 INFO - 'name': 'check_screen_resolution'},), 22:56:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:56:18 INFO - 'cmd': ('bash', 22:56:18 INFO - '-c', 22:56:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:56:18 INFO - 'enabled': True, 22:56:18 INFO - 'halt_on_failure': False, 22:56:18 INFO - 'name': 'check_screen_resolution'},), 22:56:18 INFO - 'run_cmd_checks_enabled': True, 22:56:18 INFO - 'sps_profile': False, 22:56:18 INFO - 'sps_profile_interval': 0, 22:56:18 INFO - 'suite': 'dromaeojs', 22:56:18 INFO - 'system_bits': '32', 22:56:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:56:18 INFO - 'title': 't-yosemite-r7-0178', 22:56:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:56:18 INFO - 'use_talos_json': True, 22:56:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:56:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:56:18 INFO - 'work_dir': 'build'} 22:56:18 INFO - ##### 22:56:18 INFO - ##### Running clobber step. 22:56:18 INFO - ##### 22:56:18 INFO - Running pre-action listener: _resource_record_pre_action 22:56:18 INFO - Running main action method: clobber 22:56:18 INFO - rmtree: /builds/slave/test/build 22:56:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:56:21 INFO - Running post-action listener: _resource_record_post_action 22:56:21 INFO - ##### 22:56:21 INFO - ##### Running read-buildbot-config step. 22:56:21 INFO - ##### 22:56:21 INFO - Running pre-action listener: _resource_record_pre_action 22:56:21 INFO - Running main action method: read_buildbot_config 22:56:21 INFO - Using buildbot properties: 22:56:21 INFO - { 22:56:21 INFO - "project": "", 22:56:21 INFO - "product": "firefox", 22:56:21 INFO - "script_repo_revision": "production", 22:56:21 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 22:56:21 INFO - "repository": "", 22:56:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 22:56:21 INFO - "buildid": "20160713154642", 22:56:21 INFO - "pgo_build": "False", 22:56:21 INFO - "basedir": "/builds/slave/test", 22:56:21 INFO - "buildnumber": 42, 22:56:21 INFO - "slavename": "t-yosemite-r7-0178", 22:56:21 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 22:56:21 INFO - "platform": "yosemite_r7", 22:56:21 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 22:56:21 INFO - "branch": "mozilla-beta", 22:56:21 INFO - "repo_path": "releases/mozilla-beta", 22:56:21 INFO - "revision": "84c59d136a9e54be69571b75a19e8a8ccff7b8a1", 22:56:21 INFO - "stage_platform": "macosx64", 22:56:21 INFO - "builduid": "0219f93640ac4a39933847396c8d0f21", 22:56:21 INFO - "slavebuilddir": "test" 22:56:21 INFO - } 22:56:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 22:56:21 INFO - Running post-action listener: _resource_record_post_action 22:56:21 INFO - ##### 22:56:21 INFO - ##### Running download-and-extract step. 22:56:21 INFO - ##### 22:56:21 INFO - Running pre-action listener: _resource_record_pre_action 22:56:21 INFO - Running main action method: download_and_extract 22:56:21 INFO - mkdir: /builds/slave/test/build/tests 22:56:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:21 INFO - https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:56:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 22:56:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 22:56:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 22:56:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 22:56:25 INFO - Downloaded 1165 bytes. 22:56:25 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 22:56:25 INFO - Using the following test package requirements: 22:56:25 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 22:56:25 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:56:25 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 22:56:25 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:56:25 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 22:56:25 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 22:56:25 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:56:25 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 22:56:25 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 22:56:25 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:56:25 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 22:56:25 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:56:25 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 22:56:25 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:56:25 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 22:56:25 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:56:25 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 22:56:25 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 22:56:25 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')]} 22:56:25 INFO - https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:56:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 22:56:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 22:56:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 22:56:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 22:56:27 INFO - Downloaded 18314911 bytes. 22:56:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:56:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:56:30 INFO - Return code: 0 22:56:30 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 22:56:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:30 INFO - https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:56:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 22:56:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 22:56:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 22:56:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 22:56:32 INFO - Downloaded 18314911 bytes. 22:56:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:56:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:56:36 INFO - Return code: 0 22:56: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')]} 22:56:36 INFO - https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:56:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 22:56:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 22:56:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 22:56:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 22:56:37 INFO - Downloaded 10840145 bytes. 22:56:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:56:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:56:38 INFO - Return code: 0 22:56: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')]} 22:56:38 INFO - https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 22:56:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 22:56:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 22:56:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 22:56:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 22:56:40 INFO - Downloaded 86190055 bytes. 22:56:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 22:56:40 INFO - mkdir: /builds/slave/test/properties 22:56:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:56:40 INFO - Writing to file /builds/slave/test/properties/build_url 22:56:40 INFO - Contents: 22:56:40 INFO - build_url:https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 22:56:41 INFO - Running post-action listener: _resource_record_post_action 22:56:41 INFO - Running post-action listener: set_extra_try_arguments 22:56:41 INFO - ##### 22:56:41 INFO - ##### Running populate-webroot step. 22:56:41 INFO - ##### 22:56:41 INFO - Running pre-action listener: _resource_record_pre_action 22:56:41 INFO - Running main action method: populate_webroot 22:56:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:56:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:56:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:56:41 INFO - '--tppagecycles', 22:56:41 INFO - '7'], 22:56:41 INFO - 'tests': ['tsvgm']}}, 22:56:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:56:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:56:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:56:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:56:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:56:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:56:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:41 INFO - 'talos_options': ['--disable-e10s'], 22:56:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:56:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:56:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:41 INFO - 'talos_options': ['--disable-e10s'], 22:56:41 INFO - 'tests': ['damp', 'tps']}, 22:56:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:41 INFO - 'tests': ['damp', 'tps']}, 22:56:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:56:41 INFO - 'tests': ['dromaeo_dom']}, 22:56:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:56:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:56:41 INFO - 'tests': ['a11yr', 22:56:41 INFO - 'ts_paint', 22:56:41 INFO - 'tpaint', 22:56:41 INFO - 'sessionrestore', 22:56:41 INFO - 'sessionrestore_no_auto_restore', 22:56:41 INFO - 'tabpaint']}, 22:56:41 INFO - 'other-e10s': {'tests': ['a11yr', 22:56:41 INFO - 'ts_paint', 22:56:41 INFO - 'tpaint', 22:56:41 INFO - 'sessionrestore', 22:56:41 INFO - 'sessionrestore_no_auto_restore', 22:56:41 INFO - 'tabpaint']}, 22:56:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:56:41 INFO - 'ts_paint', 22:56:41 INFO - 'tpaint', 22:56:41 INFO - 'sessionrestore', 22:56:41 INFO - 'sessionrestore_no_auto_restore', 22:56:41 INFO - 'tabpaint']}, 22:56:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:56:41 INFO - 'ts_paint', 22:56:41 INFO - 'tpaint', 22:56:41 INFO - 'sessionrestore', 22:56:41 INFO - 'sessionrestore_no_auto_restore', 22:56:41 INFO - 'tabpaint']}, 22:56:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:56:41 INFO - 'tests': ['a11yr', 22:56:41 INFO - 'ts_paint', 22:56:41 INFO - 'tpaint', 22:56:41 INFO - 'sessionrestore', 22:56:41 INFO - 'sessionrestore_no_auto_restore', 22:56:41 INFO - 'tabpaint']}, 22:56:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:56:41 INFO - 'tests': ['a11yr', 22:56:41 INFO - 'ts_paint', 22:56:41 INFO - 'tpaint', 22:56:41 INFO - 'sessionrestore', 22:56:41 INFO - 'sessionrestore_no_auto_restore', 22:56:41 INFO - 'tabpaint']}, 22:56:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:56:41 INFO - 'tests': ['tsvgx', 22:56:41 INFO - 'tsvgr_opacity', 22:56:41 INFO - 'tart', 22:56:41 INFO - 'tscrollx', 22:56:41 INFO - 'cart']}, 22:56:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:56:41 INFO - 'tsvgr_opacity', 22:56:41 INFO - 'tart', 22:56:41 INFO - 'tscrollx', 22:56:41 INFO - 'cart']}, 22:56:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:41 INFO - 'talos_options': ['--disable-e10s'], 22:56:41 INFO - 'tests': ['tp5o']}, 22:56:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:41 INFO - 'tests': ['tp5o']}, 22:56:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:41 INFO - 'talos_options': ['--disable-e10s', 22:56:41 INFO - '--xperf_path', 22:56:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:56:41 INFO - 'tests': ['tp5n']}, 22:56:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:41 INFO - 'talos_options': ['--xperf_path', 22:56:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:56:41 INFO - 'tests': ['tp5n']}}, 22:56:41 INFO - 'talos.zip': {'path': '', 22:56:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:56:41 INFO - Running post-action listener: _resource_record_post_action 22:56:41 INFO - ##### 22:56:41 INFO - ##### Running create-virtualenv step. 22:56:41 INFO - ##### 22:56:41 INFO - Running pre-action listener: _resource_record_pre_action 22:56:41 INFO - Running main action method: create_virtualenv 22:56:41 INFO - Creating virtualenv /builds/slave/test/build/venv 22:56:41 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 22:56:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:56:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:56:41 INFO - Using real prefix '/tools/python27' 22:56:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:56:42 INFO - Installing distribute.............................................................................................................................................................................................done. 22:56:45 INFO - Installing pip.................done. 22:56:45 INFO - Return code: 0 22:56:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fbb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7e856bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa51e00>, '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 0x10f8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4cb20>, '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.YIIkTArXaO/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.hEgJoD2e7w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 22:56:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:56:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:56:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIIkTArXaO/Render', 22:56:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:45 INFO - 'HOME': '/Users/cltbld', 22:56:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:45 INFO - 'LOGNAME': 'cltbld', 22:56:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:45 INFO - 'MOZ_NO_REMOTE': '1', 22:56:45 INFO - 'NO_EM_RESTART': '1', 22:56:45 INFO - 'PAGER': '/bin/cat', 22:56:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:45 INFO - 'PWD': '/builds/slave/test', 22:56:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:45 INFO - 'SHELL': '/bin/bash', 22:56:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hEgJoD2e7w/Listeners', 22:56:45 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 22:56:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:45 INFO - 'USER': 'cltbld', 22:56:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:45 INFO - 'XPC_FLAGS': '0x0', 22:56:45 INFO - 'XPC_SERVICE_NAME': '0', 22:56:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:45 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 22:56:45 INFO - Cleaning up... 22:56:45 INFO - Return code: 0 22:56:45 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fbb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7e856bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa51e00>, '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 0x10f8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4cb20>, '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.YIIkTArXaO/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.hEgJoD2e7w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 22:56:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 22:56:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 22:56:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIIkTArXaO/Render', 22:56:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:45 INFO - 'HOME': '/Users/cltbld', 22:56:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:45 INFO - 'LOGNAME': 'cltbld', 22:56:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:45 INFO - 'MOZ_NO_REMOTE': '1', 22:56:45 INFO - 'NO_EM_RESTART': '1', 22:56:45 INFO - 'PAGER': '/bin/cat', 22:56:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:45 INFO - 'PWD': '/builds/slave/test', 22:56:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:45 INFO - 'SHELL': '/bin/bash', 22:56:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hEgJoD2e7w/Listeners', 22:56:45 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 22:56:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:45 INFO - 'USER': 'cltbld', 22:56:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:45 INFO - 'XPC_FLAGS': '0x0', 22:56:45 INFO - 'XPC_SERVICE_NAME': '0', 22:56:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:45 INFO - Downloading/unpacking psutil>=0.7.1 22:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:56:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:56:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:56:48 INFO - Installing collected packages: psutil 22:56:48 INFO - Running setup.py install for psutil 22:56:48 INFO - building 'psutil._psutil_osx' extension 22:56:48 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 22:56:49 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 22:56:49 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 22:56:49 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 22:56:49 INFO - building 'psutil._psutil_posix' extension 22:56:49 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 22:56:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:56:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:56:49 INFO - ^ 22:56:49 INFO - 1 warning generated. 22:56:49 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 22:56:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:56:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:56:49 INFO - Successfully installed psutil 22:56:49 INFO - Cleaning up... 22:56:50 INFO - Return code: 0 22:56:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:56:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:50 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 0x10fbb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7e856bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa51e00>, '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 0x10f8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4cb20>, '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.YIIkTArXaO/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.hEgJoD2e7w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 22:56:50 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 22:56:50 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 22:56:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIIkTArXaO/Render', 22:56:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:50 INFO - 'HOME': '/Users/cltbld', 22:56:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:50 INFO - 'LOGNAME': 'cltbld', 22:56:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:50 INFO - 'MOZ_NO_REMOTE': '1', 22:56:50 INFO - 'NO_EM_RESTART': '1', 22:56:50 INFO - 'PAGER': '/bin/cat', 22:56:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:50 INFO - 'PWD': '/builds/slave/test', 22:56:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:50 INFO - 'SHELL': '/bin/bash', 22:56:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hEgJoD2e7w/Listeners', 22:56:50 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 22:56:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:50 INFO - 'USER': 'cltbld', 22:56:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:50 INFO - 'XPC_FLAGS': '0x0', 22:56:50 INFO - 'XPC_SERVICE_NAME': '0', 22:56:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:56:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:56:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:56:52 INFO - Installing collected packages: mozsystemmonitor 22:56:52 INFO - Running setup.py install for mozsystemmonitor 22:56:53 INFO - Successfully installed mozsystemmonitor 22:56:53 INFO - Cleaning up... 22:56:53 INFO - Return code: 0 22:56:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:56:53 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')]} 22:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:53 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')]} 22:56:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:53 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 0x10fbb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7e856bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa51e00>, '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 0x10f8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4cb20>, '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.YIIkTArXaO/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.hEgJoD2e7w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 22:56:53 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 22:56:53 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 22:56:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIIkTArXaO/Render', 22:56:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:53 INFO - 'HOME': '/Users/cltbld', 22:56:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:53 INFO - 'LOGNAME': 'cltbld', 22:56:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:53 INFO - 'MOZ_NO_REMOTE': '1', 22:56:53 INFO - 'NO_EM_RESTART': '1', 22:56:53 INFO - 'PAGER': '/bin/cat', 22:56:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:53 INFO - 'PWD': '/builds/slave/test', 22:56:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:53 INFO - 'SHELL': '/bin/bash', 22:56:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hEgJoD2e7w/Listeners', 22:56:53 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 22:56:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:53 INFO - 'USER': 'cltbld', 22:56:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:53 INFO - 'XPC_FLAGS': '0x0', 22:56:53 INFO - 'XPC_SERVICE_NAME': '0', 22:56:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:53 INFO - Downloading/unpacking blobuploader==1.2.4 22:56:53 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 22:56:53 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 22:56:53 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 22:56:53 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 22:56:53 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 22:56:53 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 22:56:55 INFO - Downloading blobuploader-1.2.4.tar.gz 22:56:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:56:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:56: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 22:56: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 22:56: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 22:56: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 22:56: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 22:56: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 22:56:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:56:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:56 INFO - Downloading docopt-0.6.1.tar.gz 22:56:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:56:57 INFO - Installing collected packages: blobuploader, requests, docopt 22:56:57 INFO - Running setup.py install for blobuploader 22:56:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:56:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:56:57 INFO - Running setup.py install for requests 22:56:57 INFO - Running setup.py install for docopt 22:56:57 INFO - Successfully installed blobuploader requests docopt 22:56:57 INFO - Cleaning up... 22:56:58 INFO - Return code: 0 22:56:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:56:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:58 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 0x10fbb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7e856bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa51e00>, '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 0x10f8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4cb20>, '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.YIIkTArXaO/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.hEgJoD2e7w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 22:56:58 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 22:56:58 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 22:56:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIIkTArXaO/Render', 22:56:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:56:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:56:58 INFO - 'HOME': '/Users/cltbld', 22:56:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:56:58 INFO - 'LOGNAME': 'cltbld', 22:56:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:58 INFO - 'MOZ_NO_REMOTE': '1', 22:56:58 INFO - 'NO_EM_RESTART': '1', 22:56:58 INFO - 'PAGER': '/bin/cat', 22:56:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:56:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:58 INFO - 'PWD': '/builds/slave/test', 22:56:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:56:58 INFO - 'SHELL': '/bin/bash', 22:56:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hEgJoD2e7w/Listeners', 22:56:58 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 22:56:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:56:58 INFO - 'USER': 'cltbld', 22:56:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:56:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:56:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:58 INFO - 'XPC_FLAGS': '0x0', 22:56:58 INFO - 'XPC_SERVICE_NAME': '0', 22:56:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:56:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:56:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-dyqi_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:56:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-pvyvig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:56:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Dz9q38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:56:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-vIUD4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:56:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-PnidZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:56:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-jMs6f4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:56:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-6ZwXaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:56:59 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-ZCPpYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:56:59 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-8skEDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:56:59 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-b3cXkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:56:59 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-yyIQSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:56:59 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-3aYvQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:56:59 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-QdKjc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:56:59 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-lsVybL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:56:59 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-r9Zxrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:56:59 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-y7e_am-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:56:59 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-vbuOop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:57:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:57:00 INFO - Running setup.py install for manifestparser 22:57:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:57:00 INFO - Running setup.py install for mozcrash 22:57:00 INFO - Running setup.py install for mozdebug 22:57:00 INFO - Running setup.py install for mozdevice 22:57:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:57:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:57:00 INFO - Running setup.py install for mozfile 22:57:00 INFO - Running setup.py install for mozhttpd 22:57:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:57:00 INFO - Running setup.py install for mozinfo 22:57:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:57:01 INFO - Running setup.py install for mozInstall 22:57:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:57:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:57:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:57:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:57:01 INFO - Running setup.py install for mozleak 22:57:01 INFO - Running setup.py install for mozlog 22:57:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:57:01 INFO - Running setup.py install for moznetwork 22:57:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:57:01 INFO - Running setup.py install for mozprocess 22:57:01 INFO - Running setup.py install for mozprofile 22:57:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:57:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:57:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:57:02 INFO - Running setup.py install for mozrunner 22:57:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:57:02 INFO - Running setup.py install for mozscreenshot 22:57:02 INFO - Running setup.py install for moztest 22:57:02 INFO - Running setup.py install for mozversion 22:57:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:57:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:57:02 INFO - Cleaning up... 22:57:02 INFO - Return code: 0 22:57:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:02 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 0x10fbb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7e856bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa51e00>, '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 0x10f8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4cb20>, '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.YIIkTArXaO/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.hEgJoD2e7w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 22:57:02 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 22:57:02 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 22:57:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIIkTArXaO/Render', 22:57:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:02 INFO - 'HOME': '/Users/cltbld', 22:57:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:02 INFO - 'LOGNAME': 'cltbld', 22:57:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:02 INFO - 'MOZ_NO_REMOTE': '1', 22:57:02 INFO - 'NO_EM_RESTART': '1', 22:57:02 INFO - 'PAGER': '/bin/cat', 22:57:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:02 INFO - 'PWD': '/builds/slave/test', 22:57:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:02 INFO - 'SHELL': '/bin/bash', 22:57:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hEgJoD2e7w/Listeners', 22:57:02 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 22:57:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:02 INFO - 'USER': 'cltbld', 22:57:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:02 INFO - 'XPC_FLAGS': '0x0', 22:57:02 INFO - 'XPC_SERVICE_NAME': '0', 22:57:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:57:02 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-MWnQPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:57:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Etbiib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:57:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-KHFE36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:57:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-mANBXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:57:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Kkb3Ya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:57:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-xABQMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:57:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-qHrOMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:57:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-dcEYrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:57:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-gEtTRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:57:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-4iej95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:57:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-pagTd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:57:04 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)) 22:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:57:04 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-sPkloU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:57:04 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)) 22:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:57:04 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-LKPLKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:57:04 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)) 22:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:57:04 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-5mN0fC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:57:04 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)) 22:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:57:04 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-qPHPN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:57:04 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)) 22:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:57:04 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-faIVjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:57:04 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)) 22:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:57:04 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-ZGChye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:57:04 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)) 22:57:04 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)) 22:57:04 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)) 22:57:04 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)) 22:57:04 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)) 22:57:04 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)) 22:57:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:07 INFO - Downloading blessings-1.6.tar.gz 22:57:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:57:07 INFO - Installing collected packages: blessings 22:57:07 INFO - Running setup.py install for blessings 22:57:07 INFO - Successfully installed blessings 22:57:07 INFO - Cleaning up... 22:57:07 INFO - Return code: 0 22:57:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:57:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:57:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:57:07 INFO - Reading from file tmpfile_stdout 22:57:07 INFO - Current package versions: 22:57:07 INFO - blessings == 1.6 22:57:07 INFO - blobuploader == 1.2.4 22:57:07 INFO - docopt == 0.6.1 22:57:07 INFO - manifestparser == 1.1 22:57:07 INFO - mozInstall == 1.12 22:57:07 INFO - mozcrash == 0.17 22:57:07 INFO - mozdebug == 0.1 22:57:07 INFO - mozdevice == 0.48 22:57:07 INFO - mozfile == 1.2 22:57:07 INFO - mozhttpd == 0.7 22:57:07 INFO - mozinfo == 0.9 22:57:07 INFO - mozleak == 0.1 22:57:07 INFO - mozlog == 3.1 22:57:07 INFO - moznetwork == 0.27 22:57:07 INFO - mozprocess == 0.22 22:57:07 INFO - mozprofile == 0.28 22:57:07 INFO - mozrunner == 6.11 22:57:07 INFO - mozscreenshot == 0.1 22:57:07 INFO - mozsystemmonitor == 0.0 22:57:07 INFO - moztest == 0.7 22:57:07 INFO - mozversion == 1.4 22:57:07 INFO - psutil == 3.1.1 22:57:07 INFO - requests == 1.2.3 22:57:07 INFO - wsgiref == 0.1.2 22:57:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:57:07 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')]} 22:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:07 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')]} 22:57:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:07 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 0x10fbb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7e856bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa51e00>, '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 0x10f8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4cb20>, '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.YIIkTArXaO/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.hEgJoD2e7w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 22:57:07 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 22:57:07 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 22:57:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIIkTArXaO/Render', 22:57:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:07 INFO - 'HOME': '/Users/cltbld', 22:57:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:07 INFO - 'LOGNAME': 'cltbld', 22:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:07 INFO - 'MOZ_NO_REMOTE': '1', 22:57:07 INFO - 'NO_EM_RESTART': '1', 22:57:07 INFO - 'PAGER': '/bin/cat', 22:57:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:07 INFO - 'PWD': '/builds/slave/test', 22:57:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:07 INFO - 'SHELL': '/bin/bash', 22:57:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hEgJoD2e7w/Listeners', 22:57:07 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 22:57:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:07 INFO - 'USER': 'cltbld', 22:57:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:07 INFO - 'XPC_FLAGS': '0x0', 22:57:07 INFO - 'XPC_SERVICE_NAME': '0', 22:57:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:08 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)) 22:57:08 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)) 22:57:08 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)) 22:57:08 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)) 22:57:08 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)) 22:57:08 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)) 22:57:08 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)) 22:57:08 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)) 22:57:08 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)) 22:57:08 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)) 22:57:08 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)) 22:57:08 INFO - Cleaning up... 22:57:08 INFO - Return code: 0 22:57:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:57:08 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')]} 22:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:08 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')]} 22:57:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:08 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 0x10fbb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7e856bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa51e00>, '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 0x10f8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4cb20>, '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.YIIkTArXaO/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.hEgJoD2e7w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 22:57:08 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 22:57:08 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 22:57:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIIkTArXaO/Render', 22:57:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:08 INFO - 'HOME': '/Users/cltbld', 22:57:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:08 INFO - 'LOGNAME': 'cltbld', 22:57:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:08 INFO - 'MOZ_NO_REMOTE': '1', 22:57:08 INFO - 'NO_EM_RESTART': '1', 22:57:08 INFO - 'PAGER': '/bin/cat', 22:57:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:08 INFO - 'PWD': '/builds/slave/test', 22:57:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:08 INFO - 'SHELL': '/bin/bash', 22:57:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hEgJoD2e7w/Listeners', 22:57:08 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 22:57:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:08 INFO - 'USER': 'cltbld', 22:57:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:08 INFO - 'XPC_FLAGS': '0x0', 22:57:08 INFO - 'XPC_SERVICE_NAME': '0', 22:57:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:08 INFO - Downloading/unpacking jsonschema 22:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:57:10 INFO - Downloading/unpacking functools32 (from jsonschema) 22:57:10 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 22:57:10 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 22:57:10 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 22:57:10 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 22:57:10 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 22:57:10 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 22:57:11 INFO - Downloading functools32-3.2.3-2.tar.gz 22:57:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:57:11 INFO - warning: no files found matching '*.txt' 22:57:11 INFO - no previously-included directories found matching 'build' 22:57:11 INFO - no previously-included directories found matching 'dist' 22:57:11 INFO - no previously-included directories found matching '.git*' 22:57:11 INFO - Installing collected packages: jsonschema, functools32 22:57:11 INFO - Running setup.py install for functools32 22:57:11 INFO - warning: no files found matching '*.txt' 22:57:11 INFO - no previously-included directories found matching 'build' 22:57:11 INFO - no previously-included directories found matching 'dist' 22:57:11 INFO - no previously-included directories found matching '.git*' 22:57:11 INFO - Successfully installed jsonschema functools32 22:57:11 INFO - Cleaning up... 22:57:11 INFO - Return code: 0 22:57:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:57:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fbb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7e856bac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa51e00>, '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 0x10f8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4cb20>, '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.YIIkTArXaO/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.hEgJoD2e7w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 22:57:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:57:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:57:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIIkTArXaO/Render', 22:57:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:11 INFO - 'HOME': '/Users/cltbld', 22:57:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:11 INFO - 'LOGNAME': 'cltbld', 22:57:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:11 INFO - 'MOZ_NO_REMOTE': '1', 22:57:11 INFO - 'NO_EM_RESTART': '1', 22:57:11 INFO - 'PAGER': '/bin/cat', 22:57:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:11 INFO - 'PWD': '/builds/slave/test', 22:57:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:11 INFO - 'SHELL': '/bin/bash', 22:57:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hEgJoD2e7w/Listeners', 22:57:11 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 22:57:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:11 INFO - 'USER': 'cltbld', 22:57:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:11 INFO - 'XPC_FLAGS': '0x0', 22:57:11 INFO - 'XPC_SERVICE_NAME': '0', 22:57:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:11 INFO - Downloading/unpacking flake8 22:57: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 22:57: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 22:57: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 22:57: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 22:57: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 22:57: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 22:57:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:57:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:57: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 22:57: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 22:57: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 22:57: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 22:57: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 22:57: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 22:57:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:57:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:57: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 22:57: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 22:57: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 22:57: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 22:57: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 22:57: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 22:57:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:57:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:57:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:57:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:57:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:57:15 INFO - no previously-included directories found matching 'docs/_build' 22:57:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:57:15 INFO - Running setup.py install for pep8 22:57:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:57:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:57:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:57:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:57:15 INFO - no previously-included directories found matching 'docs/_build' 22:57:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:57:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:57:15 INFO - Cleaning up... 22:57:15 INFO - Return code: 0 22:57:15 INFO - Running post-action listener: _resource_record_post_action 22:57:15 INFO - Running post-action listener: _start_resource_monitoring 22:57:15 INFO - Starting resource monitoring. 22:57:15 INFO - ##### 22:57:15 INFO - ##### Running install step. 22:57:15 INFO - ##### 22:57:15 INFO - Running pre-action listener: _resource_record_pre_action 22:57:15 INFO - Running main action method: install 22:57:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:57:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:57:16 INFO - Reading from file tmpfile_stdout 22:57:16 INFO - Detecting whether we're running mozinstall >=1.0... 22:57:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:57:16 INFO - Reading from file tmpfile_stdout 22:57:16 INFO - Output received: 22:57:16 INFO - Usage: mozinstall [options] installer 22:57:16 INFO - Options: 22:57:16 INFO - -h, --help show this help message and exit 22:57:16 INFO - -d DEST, --destination=DEST 22:57:16 INFO - Directory to install application into. [default: 22:57:16 INFO - "/builds/slave/test"] 22:57:16 INFO - --app=APP Application being installed. [default: firefox] 22:57:16 INFO - mkdir: /builds/slave/test/build/application 22:57:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 22:57:37 INFO - Reading from file tmpfile_stdout 22:57:37 INFO - Output received: 22:57:37 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 22:57:37 INFO - Running post-action listener: _resource_record_post_action 22:57:37 INFO - ##### 22:57:37 INFO - ##### Running run-tests step. 22:57:37 INFO - ##### 22:57:37 INFO - Running pre-action listener: _resource_record_pre_action 22:57:37 INFO - Running main action method: run_tests 22:57:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:57:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:57:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:57:37 INFO - 2016-07-13 22:57:37.889 screenresolution[1650:8586] starting screenresolution argv=screenresolution get 22:57:37 INFO - 2016-07-13 22:57:37.891 screenresolution[1650:8586] Display 0: 1600x1200x32@60 22:57:37 INFO - 2016-07-13 22:57:37.897 screenresolution[1651:8589] starting screenresolution argv=screenresolution list 22:57:37 INFO - Available Modes on Display 0 22:57:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:57:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:57:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:57:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:57:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:57:38 INFO - Intel Iris: 22:57:38 INFO - Chipset Model: Intel Iris 22:57:38 INFO - Type: GPU 22:57:38 INFO - Bus: Built-In 22:57:38 INFO - VRAM (Dynamic, Max): 1536 MB 22:57:38 INFO - Vendor: Intel (0x8086) 22:57:38 INFO - Device ID: 0x0a2e 22:57:38 INFO - Revision ID: 0x0009 22:57:38 INFO - Displays: 22:57:38 INFO - Display: 22:57:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:57:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:57:38 INFO - Display Serial Number: 1600x1200 60 22:57:38 INFO - Main Display: Yes 22:57:38 INFO - Mirror: Off 22:57:38 INFO - Online: Yes 22:57:38 INFO - Rotation: Supported 22:57:38 INFO - Return code: 0 22:57:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:57:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:57:38 INFO - Python 2.7.3 22:57:38 INFO - Return code: 0 22:57:38 INFO - grabbing minidump binary from tooltool 22:57: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')]} 22:57:38 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 0x10fa51e00>, '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 0x10f8badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa4cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:57:38 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 22:57:38 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 22:57:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:57:38 INFO - Return code: 0 22:57:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:57:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:57:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:57:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:57:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:57:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:57:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:57:39 INFO - Return code: 0 22:57:39 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/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0178', '--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 22:57:39 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/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0178 --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 22:57:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YIIkTArXaO/Render', 22:57:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:39 INFO - 'HOME': '/Users/cltbld', 22:57:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:39 INFO - 'LOGNAME': 'cltbld', 22:57:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:57:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:39 INFO - 'MOZ_NO_REMOTE': '1', 22:57:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:57:39 INFO - 'NO_EM_RESTART': '1', 22:57:39 INFO - 'PAGER': '/bin/cat', 22:57:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:39 INFO - 'PWD': '/builds/slave/test', 22:57:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:57:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:39 INFO - 'SHELL': '/bin/bash', 22:57:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hEgJoD2e7w/Listeners', 22:57:39 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 22:57:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:39 INFO - 'USER': 'cltbld', 22:57:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:39 INFO - 'XPC_FLAGS': '0x0', 22:57:39 INFO - 'XPC_SERVICE_NAME': '0', 22:57:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:39 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/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0178', '--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 22:57:39 INFO - mozversion application_buildid: 20160713154642 22:57:39 INFO - mozversion application_changeset: 84c59d136a9e54be69571b75a19e8a8ccff7b8a1 22:57:39 INFO - mozversion application_display_name: Firefox 22:57:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:57:39 INFO - mozversion application_name: Firefox 22:57:39 INFO - mozversion application_remotingname: firefox 22:57:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 22:57:39 INFO - mozversion application_vendor: Mozilla 22:57:39 INFO - mozversion application_version: 48.0 22:57:39 INFO - mozversion platform_buildid: 20160713154642 22:57:39 INFO - mozversion platform_changeset: 84c59d136a9e54be69571b75a19e8a8ccff7b8a1 22:57:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 22:57:39 INFO - mozversion platform_version: 48.0 22:57:39 INFO - using testdate: 1468475859 22:57:39 INFO - actual date: 1468475859 22:57:39 INFO - starting webserver on 'localhost:49278' 22:57:39 INFO - SUITE-START | Running 2 tests 22:57:39 INFO - TEST-START | dromaeo_css 22:57:39 INFO - operating with platform_type : mac_ 22:57:39 INFO - Initialising browser for dromaeo_css test... 22:57:39 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpttEfub/profile http://localhost:49278/getInfo.html) 22:57:42 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 22:57:42 INFO - PROCESS | 1669 | colorDepth:24 22:57:42 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 22:57:42 INFO - PROCESS | 1669 | __metrics 22:57:42 INFO - PROCESS | 1669 | 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" 22:57:42 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:57:42 INFO - TEST-INFO | 1669: exit 0 22:57:42 INFO - Browser initialized. 22:57:42 INFO - Running cycle 1/1 for dromaeo_css test... 22:57:42 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpttEfub/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:57:54 INFO - PROCESS | 1673 | RSS: Main: 160608256 22:57:54 INFO - PROCESS | 1673 | 22:58:45 INFO - PROCESS | 1673 | 1468475925927 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] 23:00:56 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 23:00:57 INFO - PROCESS | 1673 | RSS: Main: 286191616 23:00:57 INFO - PROCESS | 1673 | 23:04:00 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 23:04:01 INFO - PROCESS | 1673 | RSS: Main: 303681536 23:04:01 INFO - PROCESS | 1673 | 23:07:03 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 23:07:03 INFO - PROCESS | 1673 | RSS: Main: 300335104 23:07:03 INFO - PROCESS | 1673 | 23:10:06 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 23:10:06 INFO - PROCESS | 1673 | RSS: Main: 307642368 23:10:06 INFO - PROCESS | 1673 | 23:13:09 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 23:13:10 INFO - PROCESS | 1673 | RSS: Main: 271224832 23:13:10 INFO - PROCESS | 1673 | 23:16:12 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 23:16:13 INFO - PROCESS | 1673 | RSS: Main: 314408960 23:16:13 INFO - PROCESS | 1673 | 23:16:13 INFO - PROCESS | 1673 | __start_tp_report 23:16:13 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 23:16:13 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 23:16:13 INFO - PROCESS | 1673 | |i|pagename|runs| 23:16:13 INFO - PROCESS | 1673 | |0;dojo.html;3511.596166440859;3660.213054193349;3664.2671465706867;3684.6315368463142;3742.476053352531;6005.459508644221;6005.789797357093;6058.061000544917;6058.8485287867825;6061.121137435075;5989.730462129204;6077.444000694561;6090.390960903907;6105.247473116938;6125.87748245036;4508.2561377372695;5208.166693329049;5325.0148722486365;5345.278387417688;5470.17947307905;5415;5462.962037212937;5464.644798088131;5468.507834294913;5480.123180291154;5391.379991301012;5437.700926449043;5456.61803673743;5458.61789674723;5492.956338295731;5439.755211015494;5484.228721012704;5495.642783219078;5496.230527726121;5562.332520097574;5303.973480132575;5307.65500242483;5335.439778823245;5389.164679474688;5401.162819762903;5841.4450627190345;5849.268841394825;5908.793192238251;5927.051671732502;5934.080217566241;6312.589681670676;6316.715747791314;6317.431431171217;6345.714442850082;6347.587406818542;6183.165272688175;6350.555461117724;6386.9461538846035;6393.041043843415;6445.226572811246;6077.301110372319;6097.847553811146;6098.695065246809;6119.357467465941;6119.418655227746;6524.054012168209;6524.510661700391;6540.37866402691;6546.149000629887;6546.279909209983;6171.074338849119;6188.504919606421;6292.559520833498;6316.684165791692;6336.271328797156;6358.3641635836975;6360.618362898241;6388.425041746265;6396.712147953353;6452.483801295885;6231.781887633911;6299.433051025429;6299.653519056405;6300.527460440485;6301.4328710416285;6235.563510554218;6289.276733175652;6294.370562943761;6295.433410992941;6296.307406185316;5035.597152227813;5036.06832735945;5071.96024814912;5077.8730531737;5090.694558326512;4559.247724125548;4572.359869618256;4581.564751348683;4586.220342541776;4586.908261834745;5976.85057873548;6061;6086.7260973255425;6127.387261273837;6130.233720784902;6346.143270658402;6405.711742971577;6420.133282006963;6425.068365087036;6449.064885591563;6090;6106.755729770759;6117.785877494266;6123.908141377883;6151.9234134027265;5527.115661494222;5537.833864984057;5612.635178713453;5625.54995600351;5666.603337766317;6522.934770652233;6537.248216455168;6537.477001839937;6541.869162616721;6542.443892269115;6352.491800656029;6398.168238129014;6424.710888009958;6429.7106630200815;6547.443467305332;6566.146400967844;6602.141721576164;6631.60210387359;6636.170478690164;6643.169603799525;6194.659293738888;6309.90535141964;6325.399087086719;6325.55721099537;6352.142460767922;6328.6202827830475;6355.936440635534;6381.904271436025;6395.840103997437;6399.008153736178;6235.158253635701;6266.404691554295;6267.1539342188225;6287.497000239879;6289.6540690260645;6158.784442544489;6191.349908259568;6290.685465726604;6295.087212354275;6296.370362963667;6126.975740326223;6154.169187159676;6216.844578885564;6240.12638230639;6255.24937007562;5223;6176.073588961692;6289.371062893674;6306.527010474141;6311.463525600192;6091.482224011009;6128.662923539248;6160.014397696347;6203.565750397429;6303.905441418474 23:16:13 INFO - PROCESS | 1673 | |1;ext.html;498.6085922550797;531.6969327483336;543.7743336515346;552.8009916430087;587.5652017507047;26973.865130674345;31670;32360;38819.41770873439;39793;37747.81126094372;39427.40858887119;41576.58423415765;41821.58178418223;42003.36994945078;9566.665166719165;9793.804123917553;9805.215582753399;9812.803743925117;9815.018498150199;9141.131592498701;11712.238704484223;11995.880041199563;12010.63968080959;12030.759384812298;11735.413229338541;11826.290422574673;11827;11828.349440780754;11835.644930652052;11651.94174029133;11792.705182370424;11799.233049851731;11801.350925699084;11804.586839460664;11626;11729.648110556696;11734;11734.765304693858;11747.412629368497;40575.59424405748;41078.79460602678;41115.794421027706;41116.58883411188;41240.793796031125;2904.375559254749;3268.2319654881085;3305.6033276007033;3391.118309239591;3439.6044454887506;25635.74364256352;26294.73705262942;28471.00351487688;28549.57175642367;29270.121896343142;2719.0619236363423;2842.6873043965147;2868.211241908492;2884.8413337266443;2885.2209903325984;494697;668553;679424;680519;680666;56765.71617141888;56884;56965.71517142471;56986.71506642523;57819.71090144522;54869.1769623456;55440.72279638576;55592.444075559535;55606;55617.72191139018;17811.554711132067;18009.909950450427;18044.00757958325;18404.631907361778;18414.907925460288;12751.48994040228;12952.546660866825;14100.153990760587;14393.78409323861;14499.420023198954;15479.148646824533;15499;15529.922350388175;15534;15667.451639192574;6752.257251702309;6831.11195544586;6880.793576192722;6934.618595977269;6954.65226738661;846.2933450568844;887.6937456577471;972.7859870828406;1130.5195292000867;1210.2375503432781;28368;28550.714492855222;28967;28977.7102228975;29108.27229319284;833.5457175839061;1164.7262893220086;1165.347405452949;1171.77736230116;1251.030451400172;14331.910490234139;15363.539093827203;15431;15539.902113214797;15646.2176891155;548831;553891;562555;564970.3502965;566385;60754.78490430167;61223.69388153031;61286;61299.08051379233;61403.15790526292;1408.4507042253529;1531.0507485359149;1536.4622382166153;1549.9070055796687;1554.8989315694444;23534.529309413374;24690.629640555046;29048.12855614335;29724.405511890072;29817;45338.546614533436;45550.77224613856;45677.314840276755;45848.312275316566;45963.77018114888;426111;428639.8568007265;429810;589565;604578;34722;35828.462573060904;36730.89807305785;36801.63198367982;36807.07982300464;44377;44606.77696611626;44641.057117478515;45056;45375;37177;37426.6257337434;37464.25071498609;37613.2477350446;38356.80821595874;36888.81555592205;36993.07517311981;37222.44166337453;37267.881963541135;37700.05749856275;36743;37056.44415333718;37282;37335.81332093322;37526.06184845401;31117.8444107778;32067.67932320647;32154.517682234316;32268.515972260906;32271.838640806647;24643;24658;24877;25132.874335628207;25162.874185628956 23:16:13 INFO - PROCESS | 1673 | |2;jquery.html;402.73017078557353;418.3766188379314;418.43511259799294;419.71039982412134;428.515777171796;17777.555561110945;20575.691364629525;21309.36071917843;21417;23369.76630233697;21835.454113647167;22112.778872211275;22169.2240771573;22661.320160395215;22884.77115228847;5243.475652434764;5555.111182210847;5555.749991250404;5555.861103472425;5559.833205003836;4144.46122004139;4352.238358287303;5335.813246536372;5491.615586908918;5731.799387021455;5767.682777447239;5802.970985145068;5847.327557330919;5866.823995280149;5887.617304875191;5729.05470597353;5742.913856292159;5827.970860145714;6061.484628842789;6065.575409721321;4100.507939047334;5746.109353050284;5868.882622347572;5882.264716910387;6030.879382412371;14004.789928151085;14121.435142594284;14271.643208919757;14288.714225715428;26360.604590930958;2091.059023439443;2213.103693004336;2216.013873826148;2297.173017713622;2795.3011747063233;10101.797964040752;10191.49042547877;10639;12598.866102050768;15240.771388429182;1710.5210541048527;1777.5644966983043;1793.0496836676582;1816.9909150454296;1827.168638269584;84751;116742.99999999916;118380;120191.3990430051;128416;11737.941310293394;11743.236689615147;11764.58823941158;11858.703532411759;12057.758844823054;15611;15670;15697.450589229322;15724;15734.606634834221;9051.14014168653;9174.036726143791;9281.953590232006;9307.441553506811;9344.813103738023;7996.880046799302;9356.06439356059;9383.765405864771;9463.952680236554;9482.38364506305;4007.959920400817;4705.153072446924;4872.756362181876;4882.2432522959;4900.681455705368;1686.822883597229;1810.00368686248;1987.2710741654853;2045.1103769860158;2078.2206672497814;915.6539886367029;952.1953948913157;981.1169947047714;1020.9795804083876;1027.4811220333684;19316.420507384802;19935.800641993395;20623;21026.789732102483;22332;956.0969798058148;959.8608201810698;1001.5142655811925;1004.9547770350351;1033.2715435617872;11168.050715689094;11257.662270131745;11519.88480115205;11542.422878856192;11542.769144617061;563954;611085;666792;681234;682512;127679;138387.30806345903;148582;154852.2257388706;160993;467.84093408241364;490.4335492506161;492.15349598690193;494.08840688929587;1015.2943704125562;8878.245349145394;10913.963173498505;11491.597794077166;18323.175457104728;18375;48816.511834881196;48851.755741221066;50291.24563131483;51332.74333628458;55729.164062538286;69702.65148674427;70839;71225.64387178031;71645;72484.27515724987;8240.546769927712;8270.37972152079;8295.792605184919;8640.78398040054;8643.308535317285;9503.382280151767;12677;12684.619461416172;13240.20558766477;13298.667533311746;7978.640961156878;11390.430478476208;11735.002524785488;11772.70568235801;11813.822792657946;10093.495325233856;10696.679099627023;11149.665510034712;11293.491792869505;11608.12939029593;11657.475413606238;11725.472353743931;11835.198524463567;11935.82096268574;12068.336241506802;8300.792480188044;8382.706605268786;8442.746717598482;8467.153284671484;8878.51168185756;8419.031811341774;9378.202852757591;10546.314489558461;11535.250208736405;11815.291082535075 23:16:13 INFO - PROCESS | 1673 | |3;mootools.html;1499.1379956525002;1588.9205539723014;1621.424394340009;1715.5196544967414;1994.3318988138963;8334.458260213089;8578.056413794486;8593.355498337634;8604.741857744262;8890.466572005667;8941.105889411056;8992.190702836744;9077.591508382122;9085.091490850911;9098.317626178046;2562.6796650418696;2595.4160313929406;2600.1939398786344;2604.583266677332;2608.2566468556465;2235.944101397462;2476.319012271622;2506.5362907862013;2577.355661084729;2585.4829034193144;2541.3900663840636;2558.974410255892;2596.623489594017;2598.259496043628;2600.4279058607076;2551.4386834896295;2587.5471792436247;2594.636750854882;2600.180943002948;2600.6489123968213;2554.6678931738943;2571.7299683533333;2599.324175714309;2601.1546247469646;2609.2172348295626;9147.496887671176;9204.493752843591;9212.216961558275;9232.538373081386;9254.537273136317;5566.471185237437;5628.943710562883;5733.913991290134;5781.103928891029;5829.854253643651;3652.579953305351;3729.6083911189207;3758.0792705787258;3774.358359078963;3778.4143457763817;1054.984175237372;1057.196530636714;1086.397049637451;1097.1661537231762;1107.767368852542;12570.622881313575;12667.049971252192;12681.809772853414;12683.048771342093;12684.985201183883;4821.300911367832;5072.746362681851;5202.973985130126;5215.16557350822;5227.895442091137;8188.713395031145;9653.03469653043;9705.708828735149;9780.706578802647;9786.070323319273;4683.648726345537;4712.976435117803;5629.8592535186945;5648.322201335866;5663.405342439067;4688.71867687933;4852.8786780330065;4999.47505511916;5156;5270.868228294246;4048.959510404917;4104.240715467648;4170.852011214227;4181.414601955728;4208.431861698693;1269.397036407699;1272.0459655258555;1286.1125823181974;1298.7401920657987;1300.5448093167504;804.239993206426;885.8139790643908;909.8635204719345;937.9343445958717;969.3747532841278;5924;5935.942989360483;6127.754889804447;6237.251529816451;6238.469730072995;412.4020170752421;460.2681736039646;482.36568911880767;493.508958586209;497.03575064374996;3927.136030073379;3985.621365970228;3987.322155233617;4003.8598649047144;4047.4131250968444;11121.666350009513;11401;11463.885361146282;11487.310761354345;11499.137564682514;11251.549938002388;11269.830952535718;11275.32348059119;11331.206815522833;11331.716707082223;8302.626381812834;8705.477671339866;8706.042335343107;8729;8748.1251874812;6038.63768173911;6099.603525770811;6150.415710507674;6176.8764624706355;6231.594946328474;9861.260405469762;10043.096121349114;10132.544035518273;10137.290389672653;10178.236632252463;10157.18742500613;10447.425391603534;10471.42407167613;10648.361098334124;10663;5595;6921.653917304014;7828.334591559781;8043.87934180976;8162.469439486415;8656.956715216636;8660.566971651266;8725.258353040063;8726.607302671267;8746.081661425458;7633.503822251758;7703.229677032252;7788.727394541391;7869.527828330319;7881.290683838482;7555.6977720890545;7614.162442131472;7622.885656715264;7761.805954850948;7765.61171941412;7084.114485689289;7303.963480182565;7319.304666056716;7325.743598974011;7335.523190992569;4009.3384591542067;4483.910321793481;4873.926891096566;4913.557779799835;5124.256982737556;5151.175811870083;5185.870353241048;5190.766415511235;5214.661047031931;5215.608829337891 23:16:13 INFO - PROCESS | 1673 | |4;prototype.html;330.69741186814065;334.61853487024797;340.80063163049624;346.48546907841836;346.57717584546873;6581.572197807145;6592.340765923412;6614.5369824112395;6680.265170831205;6683;5199.532042116209;6539.378759017885;6545.214574251097;6556.344365563453;6582.012698095288;4644.280136578827;4779.545943135414;4844;4844.055409195208;4847.636427267969;3952.5256969163743;4811.35046768687;4848.97575512122;4873.366462359888;4920.70475771453;4815;4836.153673107192;4840.685355451902;4873.8781530461665;4878.243872199797;4892.217245240744;4903.681260718077;4904.975475122619;4919.72941488218;4921;3991.880243592697;4062.5531191568903;4119.649829764474;4156.189543039109;4163.2298024865495;4934.703917764909;6277.591956522857;6308.337624549402;6342.39747224013;6347.365263473662;5721.628094173865;5735.11105778605;5735.913961290584;5737.082066869322;5738.942610573924;6990.755323563651;6991.650417479156;7002.369786719169;7003.964980175067;7008.439324853999;4520.231560634667;4655.4180727409075;4677.415323084614;4688.249880019181;4691.741954192518;7274.4907856449545;7399.223081576411;7401.037865077506;7413.777586672409;7418.146913104954;4785.760711964388;4823.2282834746275;4826.8069277228515;4828.420589529279;4833.4199896012;4906.852794416173;4913.115639184981;4913.508649135058;4921.360223170965;4925.532074452921;5742.08126699726;5747.741351639186;5759.424057594207;5765.596408251382;5773.855653608609;6166.383361663798;6458.192725909262;7059.682314295869;7075.82310442243;7084.929150708427;1509.6603264265673;1616.9029858208546;1638.4674980631341;1648.69499142659;1656.2546853915785;1036.3108532825738;1037.061459379263;1052.6894566103017;1059.2743970380313;1063.1441689440076;6431.099646049557;6593.703283352261;6629.303923088103;6658.234303055114;6726.865462690719;7232.421406287484;7251.963740181371;7306.671199796022;7404.777856664308;7407.740729074457;5437.564994800486;6730.899036514456;6734.966325168343;6766.560173588701;6787.66061696923;3831.3103641344824;3877.3214687429586;3883;3883.3980732986433;3889.5527014393706;7360.447966402541;7652.502587331805;7654.73208437702;7662.5785581792425;7681.039870016248;7555.471117021865;7568.848623027508;7571.886421703679;7578;7613.505122167152;7631;7639.770806875803;7647.847043059108;7657.7319793808065;7669.578173200527;7366.631668416665;7367.2264412235945;7367.631618419165;7372.852542949218;7376.520526165782;7011.360141379279;7119.288071193047;7246.818829529304;7248.130224373109;7269.527480713737;7581.355584775135;7638.656260468181;7653.469306138683;7660.310572048543;7662.616869156408;4684.110019096361;4697.13103075932;4702.365180700562;4707.411573553306;4708.011317623337;6556.0493728410065;6735.663216839236;7337.926620733724;7338.15611204697;7353.154387245535;6809.250982392032;7117.679704413417;7124.145102587722;7137.678804453916;7147.249538798351;7056;7105.756492613876;7106.6446677666945;7114.253003434772;7120.145582529923;6949.409300209539;6956.026156338015;6960.164780226405;6961.303869612998;6967.7212911482975;7052;7065.717371305091;7083.929160708327;7099.680514376762;7102.609356485236;6661.233958094687;6671.499637527108;6968.895466568107;6992.895106573507;7001.934429343458 23:16:13 INFO - PROCESS | 1673 | |5;yui.html;678.7895752316781;722.9096362954629;723.8877973914042;757.8901059346395;787.2678409079554;15420;15427.228638568069;15618.375264989416;15623.984441011326;15628.062316261032;14403.495877644285;14438.422463101464;14471.276436178201;14480;14510.27448627567;772.7643068863994;789.5341748368461;944.6268723854073;947.1854205383365;972.2610815779988;952.8951815300311;962.3888830592554;965.9420434773936;967.4582233949011;969.9515024248794;1011.5397494140185;1022.0801278849058;1022.5347466902579;1024.605526872156;1025.523131743739;809.0251247247082;1000.5847573257126;1014.4572653630272;1020.8519764634086;1026.1688032693542;769.4844454215634;770.8342706318113;771.3443572962992;771.6026246483066;772.0388116794627;15304.234788260543;15335.616609584738;15346.923265383713;15370.923145384313;15395.846041539553;12684.04869634781;12862.356882155855;13052.673683157996;13270.071095023352;13330.666733331744;10236;10255.589776408935;10394.064534191883;10602.04581587653;10975.664567550384;3546.8226588670464;3582.5342705448384;3598.5321908152036;3653.9086522836874;3827.3876179811095;87064;87331.56334218352;88035;89189.21621567532;89281.55359223162;9565.852097748315;9803.411795292306;9860.408375497349;9861.161801246973;9960.800783984425;22624;22624.886875565517;22641.88679056627;22693;22693;7554.848903021909;7581.3176814087;7648.34989025939;7661.808454788564;7871.803204919923;4097.44684467599;4214.452121224222;4289.691644048533;4328.091100868853;4464.374987501752;3688.428293614494;3755.455458958436;3756.1173124315765;3759.981200094037;3765.755225910307;581.9185314056035;617.5584457113185;633.5121956093874;635.2377147423018;645.6190847400056;596.5168213746879;600.5735927491443;605.452065880379;620.9223847019123;628.4532456762646;7627.656755446018;7730.884036739453;8109.310708589718;8171.795705107301;8452.070272270046;552.8452033430646;572.9083346664513;608.191105829246;611.6116266171014;641.5733537197711;3712.2389910068373;3859.1702783901596;3898.3957486589625;3921.490206273166;3968.583298753647;93005;94079;94217.05782942219;94333.5283323579;95068;59000;77327.61336193395;77376;78539;78966.6051669738;66749.33250667529;67134.66432667806;70678.7592744437;71787.64106179436;72765;6450.580712253689;7764.417668674759;7809.219078092373;7856;7877.7636670899965;41626.791866040476;46883.76558117324;48176.795580110775;48705.5129448701;50334.49665503445;71041.28958710347;71673;72266.63866680634;72342;72989.27010729825;11099;11213;11492;11764.88235117672;11769.17615766888;9969.900300996896;10920.126389888948;12128.090393220367;12221.633350999484;12816.615501534969;10471.3193642413;10808.675739727822;10839.132869370274;10945.83581246266;11566.132540059361;11234.269772464764;11594.594189203337;11663.88336116628;11732.296062236293;11781.587644432404;11408.828867566826;11497.770044599229;11698.35659038761;11809.704757381134;11954.462049207632;9123.908760912305;9317.191379877566;9347.672831450867;9375.671851485165;9642.084002019797;9223.821226154163;9391.718248452557;9424.62301507932;9463.19562837139;9664.613415463302 23:16:13 INFO - PROCESS | 1673 | __end_tp_report 23:16:13 INFO - PROCESS | 1673 | __start_cc_report 23:16:13 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1077 23:16:13 INFO - PROCESS | 1673 | __end_cc_report 23:16:13 INFO - PROCESS | 1673 | __startTimestamp1468476973041__endTimestamp 23:16:13 INFO - PROCESS | 1673 | 23:16:13 INFO - PROCESS | 1673 | ------- Summary: start ------- 23:16:13 INFO - PROCESS | 1673 | Number of tests: 6 23:16:13 INFO - PROCESS | 1673 | 23:16:13 INFO - PROCESS | 1673 | [#0] dojo.html Cycles:165 Average:5944.46 Median:6171.07 stddev:634.50 (10.3%) stddev-sans-first:607.07 23:16:13 INFO - PROCESS | 1673 | Values: 3511.6 3660.2 3664.3 3684.6 3742.5 6005.5 6005.8 6058.1 6058.8 6061.1 5989.7 6077.4 6090.4 6105.2 6125.9 4508.3 5208.2 5325.0 5345.3 5470.2 5415.0 5463.0 5464.6 5468.5 5480.1 5391.4 5437.7 5456.6 5458.6 5493.0 5439.8 5484.2 5495.6 5496.2 5562.3 5304.0 5307.7 5335.4 5389.2 5401.2 5841.4 5849.3 5908.8 5927.1 5934.1 6312.6 6316.7 6317.4 6345.7 6347.6 6183.2 6350.6 6386.9 6393.0 6445.2 6077.3 6097.8 6098.7 6119.4 6119.4 6524.1 6524.5 6540.4 6546.1 6546.3 6171.1 6188.5 6292.6 6316.7 6336.3 6358.4 6360.6 6388.4 6396.7 6452.5 6231.8 6299.4 6299.7 6300.5 6301.4 6235.6 6289.3 6294.4 6295.4 6296.3 5035.6 5036.1 5072.0 5077.9 5090.7 4559.2 4572.4 4581.6 4586.2 4586.9 5976.9 6061.0 6086.7 6127.4 6130.2 6346.1 6405.7 6420.1 6425.1 6449.1 6090.0 6106.8 6117.8 6123.9 6151.9 5527.1 5537.8 5612.6 5625.5 5666.6 6522.9 6537.2 6537.5 6541.9 6542.4 6352.5 6398.2 6424.7 6429.7 6547.4 6566.1 6602.1 6631.6 6636.2 6643.2 6194.7 6309.9 6325.4 6325.6 6352.1 6328.6 6355.9 6381.9 6395.8 6399.0 6235.2 6266.4 6267.2 6287.5 6289.7 6158.8 6191.3 6290.7 6295.1 6296.4 6127.0 6154.2 6216.8 6240.1 6255.2 5223.0 6176.1 6289.4 6306.5 6311.5 6091.5 6128.7 6160.0 6203.6 6303.9 23:16:13 INFO - PROCESS | 1673 | 23:16:13 INFO - PROCESS | 1673 | [#1] ext.html Cycles:180 Average:69461.80 Median:36736.95 stddev:152962.21 (416.4%) stddev-sans-first:153303.67 23:16:13 INFO - PROCESS | 1673 | Values: 498.6 531.7 543.8 552.8 587.6 26973.9 31670.0 32360.0 38819.4 39793.0 37747.8 39427.4 41576.6 41821.6 42003.4 9566.7 9793.8 9805.2 9812.8 9815.0 9141.1 11712.2 11995.9 12010.6 12030.8 11735.4 11826.3 11827.0 11828.3 11835.6 11651.9 11792.7 11799.2 11801.4 11804.6 11626.0 11729.6 11734.0 11734.8 11747.4 40575.6 41078.8 41115.8 41116.6 41240.8 2904.4 3268.2 3305.6 3391.1 3439.6 25635.7 26294.7 28471.0 28549.6 29270.1 2719.1 2842.7 2868.2 2884.8 2885.2 494697.0 668553.0 679424.0 680519.0 680666.0 56765.7 56884.0 56965.7 56986.7 57819.7 54869.2 55440.7 55592.4 55606.0 55617.7 17811.6 18009.9 18044.0 18404.6 18414.9 12751.5 12952.5 14100.2 14393.8 14499.4 15479.1 15499.0 15529.9 15534.0 15667.5 6752.3 6831.1 6880.8 6934.6 6954.7 846.3 887.7 972.8 1130.5 1210.2 28368.0 28550.7 28967.0 28977.7 29108.3 833.5 1164.7 1165.3 1171.8 1251.0 14331.9 15363.5 15431.0 15539.9 15646.2 548831.0 553891.0 562555.0 564970.4 566385.0 60754.8 61223.7 61286.0 61299.1 61403.2 1408.5 1531.1 1536.5 1549.9 1554.9 23534.5 24690.6 29048.1 29724.4 29817.0 45338.5 45550.8 45677.3 45848.3 45963.8 426111.0 428639.9 429810.0 589565.0 604578.0 34722.0 35828.5 36730.9 36801.6 36807.1 44377.0 44606.8 44641.1 45056.0 45375.0 37177.0 37426.6 37464.3 37613.2 38356.8 36888.8 36993.1 37222.4 37267.9 37700.1 36743.0 37056.4 37282.0 37335.8 37526.1 31117.8 32067.7 32154.5 32268.5 32271.8 24643.0 24658.0 24877.0 25132.9 25162.9 23:16:13 INFO - PROCESS | 1673 | 23:16:13 INFO - PROCESS | 1673 | [#2] jquery.html Cycles:180 Average:36137.08 Median:12590.97 stddev:107299.36 (852.2%) stddev-sans-first:107566.81 23:16:13 INFO - PROCESS | 1673 | Values: 402.7 418.4 418.4 419.7 428.5 17777.6 20575.7 21309.4 21417.0 23369.8 21835.5 22112.8 22169.2 22661.3 22884.8 5243.5 5555.1 5555.7 5555.9 5559.8 4144.5 4352.2 5335.8 5491.6 5731.8 5767.7 5803.0 5847.3 5866.8 5887.6 5729.1 5742.9 5828.0 6061.5 6065.6 4100.5 5746.1 5868.9 5882.3 6030.9 14004.8 14121.4 14271.6 14288.7 26360.6 2091.1 2213.1 2216.0 2297.2 2795.3 10101.8 10191.5 10639.0 12598.9 15240.8 1710.5 1777.6 1793.0 1817.0 1827.2 84751.0 116743.0 118380.0 120191.4 128416.0 11737.9 11743.2 11764.6 11858.7 12057.8 15611.0 15670.0 15697.5 15724.0 15734.6 9051.1 9174.0 9282.0 9307.4 9344.8 7996.9 9356.1 9383.8 9464.0 9482.4 4008.0 4705.2 4872.8 4882.2 4900.7 1686.8 1810.0 1987.3 2045.1 2078.2 915.7 952.2 981.1 1021.0 1027.5 19316.4 19935.8 20623.0 21026.8 22332.0 956.1 959.9 1001.5 1005.0 1033.3 11168.1 11257.7 11519.9 11542.4 11542.8 563954.0 611085.0 666792.0 681234.0 682512.0 127679.0 138387.3 148582.0 154852.2 160993.0 467.8 490.4 492.2 494.1 1015.3 8878.2 10914.0 11491.6 18323.2 18375.0 48816.5 48851.8 50291.2 51332.7 55729.2 69702.7 70839.0 71225.6 71645.0 72484.3 8240.5 8270.4 8295.8 8640.8 8643.3 9503.4 12677.0 12684.6 13240.2 13298.7 7978.6 11390.4 11735.0 11772.7 11813.8 10093.5 10696.7 11149.7 11293.5 11608.1 11657.5 11725.5 11835.2 11935.8 12068.3 8300.8 8382.7 8442.7 8467.2 8878.5 8419.0 9378.2 10546.3 11535.3 11815.3 23:16:13 INFO - PROCESS | 1673 | 23:16:13 INFO - PROCESS | 1673 | [#3] mootools.html Cycles:180 Average:5911.86 Median:2703.53 stddev:3340.20 (123.5%) stddev-sans-first:3333.11 23:16:13 INFO - PROCESS | 1673 | Values: 1499.1 1588.9 1621.4 1715.5 1994.3 8334.5 8578.1 8593.4 8604.7 8890.5 8941.1 8992.2 9077.6 9085.1 9098.3 2562.7 2595.4 2600.2 2604.6 2608.3 2235.9 2476.3 2506.5 2577.4 2585.5 2541.4 2559.0 2596.6 2598.3 2600.4 2551.4 2587.5 2594.6 2600.2 2600.6 2554.7 2571.7 2599.3 2601.2 2609.2 9147.5 9204.5 9212.2 9232.5 9254.5 5566.5 5628.9 5733.9 5781.1 5829.9 3652.6 3729.6 3758.1 3774.4 3778.4 1055.0 1057.2 1086.4 1097.2 1107.8 12570.6 12667.0 12681.8 12683.0 12685.0 4821.3 5072.7 5203.0 5215.2 5227.9 8188.7 9653.0 9705.7 9780.7 9786.1 4683.6 4713.0 5629.9 5648.3 5663.4 4688.7 4852.9 4999.5 5156.0 5270.9 4049.0 4104.2 4170.9 4181.4 4208.4 1269.4 1272.0 1286.1 1298.7 1300.5 804.2 885.8 909.9 937.9 969.4 5924.0 5935.9 6127.8 6237.3 6238.5 412.4 460.3 482.4 493.5 497.0 3927.1 3985.6 3987.3 4003.9 4047.4 11121.7 11401.0 11463.9 11487.3 11499.1 11251.5 11269.8 11275.3 11331.2 11331.7 8302.6 8705.5 8706.0 8729.0 8748.1 6038.6 6099.6 6150.4 6176.9 6231.6 9861.3 10043.1 10132.5 10137.3 10178.2 10157.2 10447.4 10471.4 10648.4 10663.0 5595.0 6921.7 7828.3 8043.9 8162.5 8657.0 8660.6 8725.3 8726.6 8746.1 7633.5 7703.2 7788.7 7869.5 7881.3 7555.7 7614.2 7622.9 7761.8 7765.6 7084.1 7304.0 7319.3 7325.7 7335.5 4009.3 4483.9 4873.9 4913.6 5124.3 5151.2 5185.9 5190.8 5214.7 5215.6 23:16:13 INFO - PROCESS | 1673 | 23:16:13 INFO - PROCESS | 1673 | [#4] prototype.html Cycles:180 Average:5790.40 Median:6587.18 stddev:1846.30 (28.0%) stddev-sans-first:1805.43 23:16:13 INFO - PROCESS | 1673 | Values: 330.7 334.6 340.8 346.5 346.6 6581.6 6592.3 6614.5 6680.3 6683.0 5199.5 6539.4 6545.2 6556.3 6582.0 4644.3 4779.5 4844.0 4844.1 4847.6 3952.5 4811.4 4849.0 4873.4 4920.7 4815.0 4836.2 4840.7 4873.9 4878.2 4892.2 4903.7 4905.0 4919.7 4921.0 3991.9 4062.6 4119.6 4156.2 4163.2 4934.7 6277.6 6308.3 6342.4 6347.4 5721.6 5735.1 5735.9 5737.1 5738.9 6990.8 6991.7 7002.4 7004.0 7008.4 4520.2 4655.4 4677.4 4688.2 4691.7 7274.5 7399.2 7401.0 7413.8 7418.1 4785.8 4823.2 4826.8 4828.4 4833.4 4906.9 4913.1 4913.5 4921.4 4925.5 5742.1 5747.7 5759.4 5765.6 5773.9 6166.4 6458.2 7059.7 7075.8 7084.9 1509.7 1616.9 1638.5 1648.7 1656.3 1036.3 1037.1 1052.7 1059.3 1063.1 6431.1 6593.7 6629.3 6658.2 6726.9 7232.4 7252.0 7306.7 7404.8 7407.7 5437.6 6730.9 6735.0 6766.6 6787.7 3831.3 3877.3 3883.0 3883.4 3889.6 7360.4 7652.5 7654.7 7662.6 7681.0 7555.5 7568.8 7571.9 7578.0 7613.5 7631.0 7639.8 7647.8 7657.7 7669.6 7366.6 7367.2 7367.6 7372.9 7376.5 7011.4 7119.3 7246.8 7248.1 7269.5 7581.4 7638.7 7653.5 7660.3 7662.6 4684.1 4697.1 4702.4 4707.4 4708.0 6556.0 6735.7 7337.9 7338.2 7353.2 6809.3 7117.7 7124.1 7137.7 7147.2 7056.0 7105.8 7106.6 7114.3 7120.1 6949.4 6956.0 6960.2 6961.3 6967.7 7052.0 7065.7 7083.9 7099.7 7102.6 6661.2 6671.5 6968.9 6992.9 7001.9 23:16:13 INFO - PROCESS | 1673 | 23:16:13 INFO - PROCESS | 1673 | [#5] yui.html Cycles:180 Average:18582.60 Median:29790.96 stddev:26289.41 (88.2%) stddev-sans-first:26328.79 23:16:13 INFO - PROCESS | 1673 | Values: 678.8 722.9 723.9 757.9 787.3 15420.0 15427.2 15618.4 15624.0 15628.1 14403.5 14438.4 14471.3 14480.0 14510.3 772.8 789.5 944.6 947.2 972.3 952.9 962.4 965.9 967.5 970.0 1011.5 1022.1 1022.5 1024.6 1025.5 809.0 1000.6 1014.5 1020.9 1026.2 769.5 770.8 771.3 771.6 772.0 15304.2 15335.6 15346.9 15370.9 15395.8 12684.0 12862.4 13052.7 13270.1 13330.7 10236.0 10255.6 10394.1 10602.0 10975.7 3546.8 3582.5 3598.5 3653.9 3827.4 87064.0 87331.6 88035.0 89189.2 89281.6 9565.9 9803.4 9860.4 9861.2 9960.8 22624.0 22624.9 22641.9 22693.0 22693.0 7554.8 7581.3 7648.3 7661.8 7871.8 4097.4 4214.5 4289.7 4328.1 4464.4 3688.4 3755.5 3756.1 3760.0 3765.8 581.9 617.6 633.5 635.2 645.6 596.5 600.6 605.5 620.9 628.5 7627.7 7730.9 8109.3 8171.8 8452.1 552.8 572.9 608.2 611.6 641.6 3712.2 3859.2 3898.4 3921.5 3968.6 93005.0 94079.0 94217.1 94333.5 95068.0 59000.0 77327.6 77376.0 78539.0 78966.6 66749.3 67134.7 70678.8 71787.6 72765.0 6450.6 7764.4 7809.2 7856.0 7877.8 41626.8 46883.8 48176.8 48705.5 50334.5 71041.3 71673.0 72266.6 72342.0 72989.3 11099.0 11213.0 11492.0 11764.9 11769.2 9969.9 10920.1 12128.1 12221.6 12816.6 10471.3 10808.7 10839.1 10945.8 11566.1 11234.3 11594.6 11663.9 11732.3 11781.6 11408.8 11497.8 11698.4 11809.7 11954.5 9123.9 9317.2 9347.7 9375.7 9642.1 9223.8 9391.7 9424.6 9463.2 9664.6 23:16:13 INFO - PROCESS | 1673 | -------- Summary: end -------- 23:16:13 INFO - PROCESS | 1673 | 23:16:13 INFO - TEST-INFO | 1673: exit 0 23:16:13 INFO - TEST-OK | dromaeo_css | took 1114028ms 23:16:13 INFO - TEST-START | kraken 23:16:13 INFO - operating with platform_type : mac_ 23:16:13 INFO - Initialising browser for kraken test... 23:16:13 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpGxS9Bt/profile http://localhost:49278/getInfo.html) 23:16:15 INFO - PROCESS | 1684 | __metrics Screen width/height:1600/1200 23:16:15 INFO - PROCESS | 1684 | colorDepth:24 23:16:15 INFO - PROCESS | 1684 | Browser inner width/height: 1024/689 23:16:15 INFO - PROCESS | 1684 | __metrics 23:16:15 INFO - PROCESS | 1684 | 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" 23:16:15 ERROR - PROCESS | 1684 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:16:16 INFO - TEST-INFO | 1684: exit 0 23:16:16 INFO - Browser initialized. 23:16:16 INFO - Running cycle 1/1 for kraken test... 23:16:16 INFO - TEST-INFO | started process 1687 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpGxS9Bt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:16:28 INFO - PROCESS | 1687 | RSS: Main: 160440320 23:16:28 INFO - PROCESS | 1687 | 23:16:30 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 23:16:31 INFO - PROCESS | 1687 | RSS: Main: 224022528 23:16:31 INFO - PROCESS | 1687 | 23:16:34 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 23:16:35 INFO - PROCESS | 1687 | RSS: Main: 239833088 23:16:35 INFO - PROCESS | 1687 | 23:16:38 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 23:16:38 INFO - PROCESS | 1687 | RSS: Main: 326578176 23:16:38 INFO - PROCESS | 1687 | 23:16:40 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 23:16:41 INFO - PROCESS | 1687 | RSS: Main: 230780928 23:16:41 INFO - PROCESS | 1687 | 23:16:43 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:16:44 INFO - PROCESS | 1687 | RSS: Main: 231182336 23:16:44 INFO - PROCESS | 1687 | 23:16:46 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 23:16:47 INFO - PROCESS | 1687 | RSS: Main: 268500992 23:16:47 INFO - PROCESS | 1687 | 23:16:50 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 23:16:51 INFO - PROCESS | 1687 | RSS: Main: 271044608 23:16:51 INFO - PROCESS | 1687 | 23:16:53 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 23:16:54 INFO - PROCESS | 1687 | RSS: Main: 269242368 23:16:54 INFO - PROCESS | 1687 | 23:16:56 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:16:56 INFO - PROCESS | 1687 | RSS: Main: 260976640 23:16:56 INFO - PROCESS | 1687 | 23:16:58 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:16:59 INFO - PROCESS | 1687 | RSS: Main: 267845632 23:16:59 INFO - PROCESS | 1687 | 23:17:01 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:17:01 INFO - PROCESS | 1687 | RSS: Main: 271282176 23:17:01 INFO - PROCESS | 1687 | 23:17:04 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:17:05 INFO - PROCESS | 1687 | RSS: Main: 278564864 23:17:05 INFO - PROCESS | 1687 | 23:17:08 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:17:08 INFO - PROCESS | 1687 | RSS: Main: 275509248 23:17:08 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 23:17:10 INFO - PROCESS | 1687 | RSS: Main: 336281600 23:17:10 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | __start_tp_report 23:17:10 INFO - PROCESS | 1687 | _x_x_mozilla_page_load 23:17:10 INFO - PROCESS | 1687 | _x_x_mozilla_page_load_details 23:17:10 INFO - PROCESS | 1687 | |i|pagename|runs| 23:17:10 INFO - PROCESS | 1687 | |0;ai-astar;113;114;118;114;112;112;113;114;114;135 23:17:10 INFO - PROCESS | 1687 | |1;audio-beat-detection;157;121;142;165;117;140;121;178;165;111 23:17:10 INFO - PROCESS | 1687 | |2;audio-dft;187;241;258;185;183;184;184;186;186;185 23:17:10 INFO - PROCESS | 1687 | |3;audio-fft;69;78;75;152;56;56;59;63;129;59 23:17:10 INFO - PROCESS | 1687 | |4;audio-oscillator;89;92;139;79;86;86;126;80;79;128 23:17:10 INFO - PROCESS | 1687 | |5;imaging-gaussian-blur;87;85;88;85;87;83;89;85;88;84 23:17:10 INFO - PROCESS | 1687 | |6;imaging-darkroom;93;94;93;93;94;93;92;93;116;150 23:17:10 INFO - PROCESS | 1687 | |7;imaging-desaturate;87;86;86;85;86;86;84;86;84;84 23:17:10 INFO - PROCESS | 1687 | |8;json-parse-financial;58;62;62;62;62;63;63;77;58;57 23:17:10 INFO - PROCESS | 1687 | |9;json-stringify-tinderbox;61;62;64;75;76;73;68;66;68;67 23:17:10 INFO - PROCESS | 1687 | |10;stanford-crypto-aes;80;81;78;80;80;79;78;77;77;83 23:17:10 INFO - PROCESS | 1687 | |11;stanford-crypto-ccm;127;127;123;126;141;111;136;142;146;167 23:17:10 INFO - PROCESS | 1687 | |12;stanford-crypto-pbkdf2;185;217;171;165;163;163;163;162;162;160 23:17:10 INFO - PROCESS | 1687 | |13;stanford-crypto-sha256-iterative;65;67;64;66;65;69;66;67;65;67 23:17:10 INFO - PROCESS | 1687 | __end_tp_report 23:17:10 INFO - PROCESS | 1687 | __start_cc_report 23:17:10 INFO - PROCESS | 1687 | _x_x_mozilla_cycle_collect,1995 23:17:10 INFO - PROCESS | 1687 | __end_cc_report 23:17:10 INFO - PROCESS | 1687 | __startTimestamp1468477030632__endTimestamp 23:17:10 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | ------- Summary: start ------- 23:17:10 INFO - PROCESS | 1687 | Number of tests: 14 23:17:10 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | [#0] ai-astar Cycles:10 Average:115.90 Median:114.00 stddev:6.92 (6.1%) stddev-sans-first:7.26 23:17:10 INFO - PROCESS | 1687 | Values: 113.0 114.0 118.0 114.0 112.0 112.0 113.0 114.0 114.0 135.0 23:17:10 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:149.50 stddev:23.69 (15.8%) stddev-sans-first:24.47 23:17:10 INFO - PROCESS | 1687 | Values: 157.0 121.0 142.0 165.0 117.0 140.0 121.0 178.0 165.0 111.0 23:17:10 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | [#2] audio-dft Cycles:10 Average:197.90 Median:186.00 stddev:27.51 (14.8%) stddev-sans-first:28.90 23:17:10 INFO - PROCESS | 1687 | Values: 187.0 241.0 258.0 185.0 183.0 184.0 184.0 186.0 186.0 185.0 23:17:10 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | [#3] audio-fft Cycles:10 Average:79.60 Median:61.00 stddev:33.43 (54.8%) stddev-sans-first:35.23 23:17:10 INFO - PROCESS | 1687 | Values: 69.0 78.0 75.0 152.0 56.0 56.0 59.0 63.0 129.0 59.0 23:17:10 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | [#4] audio-oscillator Cycles:10 Average:98.40 Median:83.00 stddev:23.13 (27.9%) stddev-sans-first:24.28 23:17:10 INFO - PROCESS | 1687 | Values: 89.0 92.0 139.0 79.0 86.0 86.0 126.0 80.0 79.0 128.0 23:17:10 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.00 stddev:1.97 (2.3%) stddev-sans-first:2.06 23:17:10 INFO - PROCESS | 1687 | Values: 87.0 85.0 88.0 85.0 87.0 83.0 89.0 85.0 88.0 84.0 23:17:10 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | [#6] imaging-darkroom Cycles:10 Average:101.10 Median:93.00 stddev:18.63 (20.0%) stddev-sans-first:19.53 23:17:10 INFO - PROCESS | 1687 | Values: 93.0 94.0 93.0 93.0 94.0 93.0 92.0 93.0 116.0 150.0 23:17:10 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:1.07 (1.2%) stddev-sans-first:0.97 23:17:10 INFO - PROCESS | 1687 | Values: 87.0 86.0 86.0 85.0 86.0 86.0 84.0 86.0 84.0 84.0 23:17:10 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:62.00 stddev:5.60 (9.0%) stddev-sans-first:5.71 23:17:10 INFO - PROCESS | 1687 | Values: 58.0 62.0 62.0 62.0 62.0 63.0 63.0 77.0 58.0 57.0 23:17:10 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:68.00 stddev:5.21 (7.7%) stddev-sans-first:4.87 23:17:10 INFO - PROCESS | 1687 | Values: 61.0 62.0 64.0 75.0 76.0 73.0 68.0 66.0 68.0 67.0 23:17:10 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | [#10] stanford-crypto-aes Cycles:10 Average:79.30 Median:80.00 stddev:1.89 (2.4%) stddev-sans-first:1.99 23:17:10 INFO - PROCESS | 1687 | Values: 80.0 81.0 78.0 80.0 80.0 79.0 78.0 77.0 77.0 83.0 23:17:10 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | [#11] stanford-crypto-ccm Cycles:10 Average:134.60 Median:138.50 stddev:15.49 (11.2%) stddev-sans-first:16.18 23:17:10 INFO - PROCESS | 1687 | Values: 127.0 127.0 123.0 126.0 141.0 111.0 136.0 142.0 146.0 167.0 23:17:10 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.10 Median:164.00 stddev:17.71 (10.8%) stddev-sans-first:18.06 23:17:10 INFO - PROCESS | 1687 | Values: 185.0 217.0 171.0 165.0 163.0 163.0 163.0 162.0 162.0 160.0 23:17:10 INFO - PROCESS | 1687 | 23:17:10 INFO - PROCESS | 1687 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:1.45 (2.2%) stddev-sans-first:1.48 23:17:10 INFO - PROCESS | 1687 | Values: 65.0 67.0 64.0 66.0 65.0 69.0 66.0 67.0 65.0 67.0 23:17:10 INFO - PROCESS | 1687 | -------- Summary: end -------- 23:17:10 INFO - PROCESS | 1687 | 23:17:11 INFO - TEST-INFO | 1687: exit 0 23:17:11 INFO - TEST-OK | kraken | took 57940ms 23:17:11 INFO - SUITE-END | took 1171s 23:17:11 INFO - Completed test suite (00:19:32) 23:17:11 INFO - javascript benchmark 23:17:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3511.596166440859, 3660.213054193349, 3664.2671465706867, 3684.6315368463142, 3742.476053352531, 6005.459508644221, 6005.789797357093, 6058.061000544917, 6058.8485287867825, 6061.121137435075, 5989.730462129204, 6077.444000694561, 6090.390960903907, 6105.247473116938, 6125.87748245036, 4508.2561377372695, 5208.166693329049, 5325.0148722486365, 5345.278387417688, 5470.17947307905, 5415.0, 5462.962037212937, 5464.644798088131, 5468.507834294913, 5480.123180291154, 5391.379991301012, 5437.700926449043, 5456.61803673743, 5458.61789674723, 5492.956338295731, 5439.755211015494, 5484.228721012704, 5495.642783219078, 5496.230527726121, 5562.332520097574, 5303.973480132575, 5307.65500242483, 5335.439778823245, 5389.164679474688, 5401.162819762903, 5841.4450627190345, 5849.268841394825, 5908.793192238251, 5927.051671732502, 5934.080217566241, 6312.589681670676, 6316.715747791314, 6317.431431171217, 6345.714442850082, 6347.587406818542, 6183.165272688175, 6350.555461117724, 6386.9461538846035, 6393.041043843415, 6445.226572811246, 6077.301110372319, 6097.847553811146, 6098.695065246809, 6119.357467465941, 6119.418655227746, 6524.054012168209, 6524.510661700391, 6540.37866402691, 6546.149000629887, 6546.279909209983, 6171.074338849119, 6188.504919606421, 6292.559520833498, 6316.684165791692, 6336.271328797156, 6358.3641635836975, 6360.618362898241, 6388.425041746265, 6396.712147953353, 6452.483801295885, 6231.781887633911, 6299.433051025429, 6299.653519056405, 6300.527460440485, 6301.4328710416285, 6235.563510554218, 6289.276733175652, 6294.370562943761, 6295.433410992941, 6296.307406185316, 5035.597152227813, 5036.06832735945, 5071.96024814912, 5077.8730531737, 5090.694558326512, 4559.247724125548, 4572.359869618256, 4581.564751348683, 4586.220342541776, 4586.908261834745, 5976.85057873548, 6061.0, 6086.7260973255425, 6127.387261273837, 6130.233720784902, 6346.143270658402, 6405.711742971577, 6420.133282006963, 6425.068365087036, 6449.064885591563, 6090.0, 6106.755729770759, 6117.785877494266, 6123.908141377883, 6151.9234134027265, 5527.115661494222, 5537.833864984057, 5612.635178713453, 5625.54995600351, 5666.603337766317, 6522.934770652233, 6537.248216455168, 6537.477001839937, 6541.869162616721, 6542.443892269115, 6352.491800656029, 6398.168238129014, 6424.710888009958, 6429.7106630200815, 6547.443467305332, 6566.146400967844, 6602.141721576164, 6631.60210387359, 6636.170478690164, 6643.169603799525, 6194.659293738888, 6309.90535141964, 6325.399087086719, 6325.55721099537, 6352.142460767922, 6328.6202827830475, 6355.936440635534, 6381.904271436025, 6395.840103997437, 6399.008153736178, 6235.158253635701, 6266.404691554295, 6267.1539342188225, 6287.497000239879, 6289.6540690260645, 6158.784442544489, 6191.349908259568, 6290.685465726604, 6295.087212354275, 6296.370362963667, 6126.975740326223, 6154.169187159676, 6216.844578885564, 6240.12638230639, 6255.24937007562, 5223.0, 6176.073588961692, 6289.371062893674, 6306.527010474141, 6311.463525600192, 6091.482224011009, 6128.662923539248, 6160.014397696347, 6203.565750397429, 6303.905441418474], "value": 5905.920029770443, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.6085922550797, 531.6969327483336, 543.7743336515346, 552.8009916430087, 587.5652017507047, 26973.865130674345, 31670.0, 32360.0, 38819.41770873439, 39793.0, 37747.81126094372, 39427.40858887119, 41576.58423415765, 41821.58178418223, 42003.36994945078, 9566.665166719165, 9793.804123917553, 9805.215582753399, 9812.803743925117, 9815.018498150199, 9141.131592498701, 11712.238704484223, 11995.880041199563, 12010.63968080959, 12030.759384812298, 11735.413229338541, 11826.290422574673, 11827.0, 11828.349440780754, 11835.644930652052, 11651.94174029133, 11792.705182370424, 11799.233049851731, 11801.350925699084, 11804.586839460664, 11626.0, 11729.648110556696, 11734.0, 11734.765304693858, 11747.412629368497, 40575.59424405748, 41078.79460602678, 41115.794421027706, 41116.58883411188, 41240.793796031125, 2904.375559254749, 3268.2319654881085, 3305.6033276007033, 3391.118309239591, 3439.6044454887506, 25635.74364256352, 26294.73705262942, 28471.00351487688, 28549.57175642367, 29270.121896343142, 2719.0619236363423, 2842.6873043965147, 2868.211241908492, 2884.8413337266443, 2885.2209903325984, 494697.0, 668553.0, 679424.0, 680519.0, 680666.0, 56765.71617141888, 56884.0, 56965.71517142471, 56986.71506642523, 57819.71090144522, 54869.1769623456, 55440.72279638576, 55592.444075559535, 55606.0, 55617.72191139018, 17811.554711132067, 18009.909950450427, 18044.00757958325, 18404.631907361778, 18414.907925460288, 12751.48994040228, 12952.546660866825, 14100.153990760587, 14393.78409323861, 14499.420023198954, 15479.148646824533, 15499.0, 15529.922350388175, 15534.0, 15667.451639192574, 6752.257251702309, 6831.11195544586, 6880.793576192722, 6934.618595977269, 6954.65226738661, 846.2933450568844, 887.6937456577471, 972.7859870828406, 1130.5195292000867, 1210.2375503432781, 28368.0, 28550.714492855222, 28967.0, 28977.7102228975, 29108.27229319284, 833.5457175839061, 1164.7262893220086, 1165.347405452949, 1171.77736230116, 1251.030451400172, 14331.910490234139, 15363.539093827203, 15431.0, 15539.902113214797, 15646.2176891155, 548831.0, 553891.0, 562555.0, 564970.3502965, 566385.0, 60754.78490430167, 61223.69388153031, 61286.0, 61299.08051379233, 61403.15790526292, 1408.4507042253529, 1531.0507485359149, 1536.4622382166153, 1549.9070055796687, 1554.8989315694444, 23534.529309413374, 24690.629640555046, 29048.12855614335, 29724.405511890072, 29817.0, 45338.546614533436, 45550.77224613856, 45677.314840276755, 45848.312275316566, 45963.77018114888, 426111.0, 428639.8568007265, 429810.0, 589565.0, 604578.0, 34722.0, 35828.462573060904, 36730.89807305785, 36801.63198367982, 36807.07982300464, 44377.0, 44606.77696611626, 44641.057117478515, 45056.0, 45375.0, 37177.0, 37426.6257337434, 37464.25071498609, 37613.2477350446, 38356.80821595874, 36888.81555592205, 36993.07517311981, 37222.44166337453, 37267.881963541135, 37700.05749856275, 36743.0, 37056.44415333718, 37282.0, 37335.81332093322, 37526.06184845401, 31117.8444107778, 32067.67932320647, 32154.517682234316, 32268.515972260906, 32271.838640806647, 24643.0, 24658.0, 24877.0, 25132.874335628207, 25162.874185628956], "value": 20099.955712512998, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.73017078557353, 418.3766188379314, 418.43511259799294, 419.71039982412134, 428.515777171796, 17777.555561110945, 20575.691364629525, 21309.36071917843, 21417.0, 23369.76630233697, 21835.454113647167, 22112.778872211275, 22169.2240771573, 22661.320160395215, 22884.77115228847, 5243.475652434764, 5555.111182210847, 5555.749991250404, 5555.861103472425, 5559.833205003836, 4144.46122004139, 4352.238358287303, 5335.813246536372, 5491.615586908918, 5731.799387021455, 5767.682777447239, 5802.970985145068, 5847.327557330919, 5866.823995280149, 5887.617304875191, 5729.05470597353, 5742.913856292159, 5827.970860145714, 6061.484628842789, 6065.575409721321, 4100.507939047334, 5746.109353050284, 5868.882622347572, 5882.264716910387, 6030.879382412371, 14004.789928151085, 14121.435142594284, 14271.643208919757, 14288.714225715428, 26360.604590930958, 2091.059023439443, 2213.103693004336, 2216.013873826148, 2297.173017713622, 2795.3011747063233, 10101.797964040752, 10191.49042547877, 10639.0, 12598.866102050768, 15240.771388429182, 1710.5210541048527, 1777.5644966983043, 1793.0496836676582, 1816.9909150454296, 1827.168638269584, 84751.0, 116742.99999999916, 118380.0, 120191.3990430051, 128416.0, 11737.941310293394, 11743.236689615147, 11764.58823941158, 11858.703532411759, 12057.758844823054, 15611.0, 15670.0, 15697.450589229322, 15724.0, 15734.606634834221, 9051.14014168653, 9174.036726143791, 9281.953590232006, 9307.441553506811, 9344.813103738023, 7996.880046799302, 9356.06439356059, 9383.765405864771, 9463.952680236554, 9482.38364506305, 4007.959920400817, 4705.153072446924, 4872.756362181876, 4882.2432522959, 4900.681455705368, 1686.822883597229, 1810.00368686248, 1987.2710741654853, 2045.1103769860158, 2078.2206672497814, 915.6539886367029, 952.1953948913157, 981.1169947047714, 1020.9795804083876, 1027.4811220333684, 19316.420507384802, 19935.800641993395, 20623.0, 21026.789732102483, 22332.0, 956.0969798058148, 959.8608201810698, 1001.5142655811925, 1004.9547770350351, 1033.2715435617872, 11168.050715689094, 11257.662270131745, 11519.88480115205, 11542.422878856192, 11542.769144617061, 563954.0, 611085.0, 666792.0, 681234.0, 682512.0, 127679.0, 138387.30806345903, 148582.0, 154852.2257388706, 160993.0, 467.84093408241364, 490.4335492506161, 492.15349598690193, 494.08840688929587, 1015.2943704125562, 8878.245349145394, 10913.963173498505, 11491.597794077166, 18323.175457104728, 18375.0, 48816.511834881196, 48851.755741221066, 50291.24563131483, 51332.74333628458, 55729.164062538286, 69702.65148674427, 70839.0, 71225.64387178031, 71645.0, 72484.27515724987, 8240.546769927712, 8270.37972152079, 8295.792605184919, 8640.78398040054, 8643.308535317285, 9503.382280151767, 12677.0, 12684.619461416172, 13240.20558766477, 13298.667533311746, 7978.640961156878, 11390.430478476208, 11735.002524785488, 11772.70568235801, 11813.822792657946, 10093.495325233856, 10696.679099627023, 11149.665510034712, 11293.491792869505, 11608.12939029593, 11657.475413606238, 11725.472353743931, 11835.198524463567, 11935.82096268574, 12068.336241506802, 8300.792480188044, 8382.706605268786, 8442.746717598482, 8467.153284671484, 8878.51168185756, 8419.031811341774, 9378.202852757591, 10546.314489558461, 11535.250208736405, 11815.291082535075], "value": 9398.037108245673, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1499.1379956525002, 1588.9205539723014, 1621.424394340009, 1715.5196544967414, 1994.3318988138963, 8334.458260213089, 8578.056413794486, 8593.355498337634, 8604.741857744262, 8890.466572005667, 8941.105889411056, 8992.190702836744, 9077.591508382122, 9085.091490850911, 9098.317626178046, 2562.6796650418696, 2595.4160313929406, 2600.1939398786344, 2604.583266677332, 2608.2566468556465, 2235.944101397462, 2476.319012271622, 2506.5362907862013, 2577.355661084729, 2585.4829034193144, 2541.3900663840636, 2558.974410255892, 2596.623489594017, 2598.259496043628, 2600.4279058607076, 2551.4386834896295, 2587.5471792436247, 2594.636750854882, 2600.180943002948, 2600.6489123968213, 2554.6678931738943, 2571.7299683533333, 2599.324175714309, 2601.1546247469646, 2609.2172348295626, 9147.496887671176, 9204.493752843591, 9212.216961558275, 9232.538373081386, 9254.537273136317, 5566.471185237437, 5628.943710562883, 5733.913991290134, 5781.103928891029, 5829.854253643651, 3652.579953305351, 3729.6083911189207, 3758.0792705787258, 3774.358359078963, 3778.4143457763817, 1054.984175237372, 1057.196530636714, 1086.397049637451, 1097.1661537231762, 1107.767368852542, 12570.622881313575, 12667.049971252192, 12681.809772853414, 12683.048771342093, 12684.985201183883, 4821.300911367832, 5072.746362681851, 5202.973985130126, 5215.16557350822, 5227.895442091137, 8188.713395031145, 9653.03469653043, 9705.708828735149, 9780.706578802647, 9786.070323319273, 4683.648726345537, 4712.976435117803, 5629.8592535186945, 5648.322201335866, 5663.405342439067, 4688.71867687933, 4852.8786780330065, 4999.47505511916, 5156.0, 5270.868228294246, 4048.959510404917, 4104.240715467648, 4170.852011214227, 4181.414601955728, 4208.431861698693, 1269.397036407699, 1272.0459655258555, 1286.1125823181974, 1298.7401920657987, 1300.5448093167504, 804.239993206426, 885.8139790643908, 909.8635204719345, 937.9343445958717, 969.3747532841278, 5924.0, 5935.942989360483, 6127.754889804447, 6237.251529816451, 6238.469730072995, 412.4020170752421, 460.2681736039646, 482.36568911880767, 493.508958586209, 497.03575064374996, 3927.136030073379, 3985.621365970228, 3987.322155233617, 4003.8598649047144, 4047.4131250968444, 11121.666350009513, 11401.0, 11463.885361146282, 11487.310761354345, 11499.137564682514, 11251.549938002388, 11269.830952535718, 11275.32348059119, 11331.206815522833, 11331.716707082223, 8302.626381812834, 8705.477671339866, 8706.042335343107, 8729.0, 8748.1251874812, 6038.63768173911, 6099.603525770811, 6150.415710507674, 6176.8764624706355, 6231.594946328474, 9861.260405469762, 10043.096121349114, 10132.544035518273, 10137.290389672653, 10178.236632252463, 10157.18742500613, 10447.425391603534, 10471.42407167613, 10648.361098334124, 10663.0, 5595.0, 6921.653917304014, 7828.334591559781, 8043.87934180976, 8162.469439486415, 8656.956715216636, 8660.566971651266, 8725.258353040063, 8726.607302671267, 8746.081661425458, 7633.503822251758, 7703.229677032252, 7788.727394541391, 7869.527828330319, 7881.290683838482, 7555.6977720890545, 7614.162442131472, 7622.885656715264, 7761.805954850948, 7765.61171941412, 7084.114485689289, 7303.963480182565, 7319.304666056716, 7325.743598974011, 7335.523190992569, 4009.3384591542067, 4483.910321793481, 4873.926891096566, 4913.557779799835, 5124.256982737556, 5151.175811870083, 5185.870353241048, 5190.766415511235, 5214.661047031931, 5215.608829337891], "value": 4676.5377543716295, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.69741186814065, 334.61853487024797, 340.80063163049624, 346.48546907841836, 346.57717584546873, 6581.572197807145, 6592.340765923412, 6614.5369824112395, 6680.265170831205, 6683.0, 5199.532042116209, 6539.378759017885, 6545.214574251097, 6556.344365563453, 6582.012698095288, 4644.280136578827, 4779.545943135414, 4844.0, 4844.055409195208, 4847.636427267969, 3952.5256969163743, 4811.35046768687, 4848.97575512122, 4873.366462359888, 4920.70475771453, 4815.0, 4836.153673107192, 4840.685355451902, 4873.8781530461665, 4878.243872199797, 4892.217245240744, 4903.681260718077, 4904.975475122619, 4919.72941488218, 4921.0, 3991.880243592697, 4062.5531191568903, 4119.649829764474, 4156.189543039109, 4163.2298024865495, 4934.703917764909, 6277.591956522857, 6308.337624549402, 6342.39747224013, 6347.365263473662, 5721.628094173865, 5735.11105778605, 5735.913961290584, 5737.082066869322, 5738.942610573924, 6990.755323563651, 6991.650417479156, 7002.369786719169, 7003.964980175067, 7008.439324853999, 4520.231560634667, 4655.4180727409075, 4677.415323084614, 4688.249880019181, 4691.741954192518, 7274.4907856449545, 7399.223081576411, 7401.037865077506, 7413.777586672409, 7418.146913104954, 4785.760711964388, 4823.2282834746275, 4826.8069277228515, 4828.420589529279, 4833.4199896012, 4906.852794416173, 4913.115639184981, 4913.508649135058, 4921.360223170965, 4925.532074452921, 5742.08126699726, 5747.741351639186, 5759.424057594207, 5765.596408251382, 5773.855653608609, 6166.383361663798, 6458.192725909262, 7059.682314295869, 7075.82310442243, 7084.929150708427, 1509.6603264265673, 1616.9029858208546, 1638.4674980631341, 1648.69499142659, 1656.2546853915785, 1036.3108532825738, 1037.061459379263, 1052.6894566103017, 1059.2743970380313, 1063.1441689440076, 6431.099646049557, 6593.703283352261, 6629.303923088103, 6658.234303055114, 6726.865462690719, 7232.421406287484, 7251.963740181371, 7306.671199796022, 7404.777856664308, 7407.740729074457, 5437.564994800486, 6730.899036514456, 6734.966325168343, 6766.560173588701, 6787.66061696923, 3831.3103641344824, 3877.3214687429586, 3883.0, 3883.3980732986433, 3889.5527014393706, 7360.447966402541, 7652.502587331805, 7654.73208437702, 7662.5785581792425, 7681.039870016248, 7555.471117021865, 7568.848623027508, 7571.886421703679, 7578.0, 7613.505122167152, 7631.0, 7639.770806875803, 7647.847043059108, 7657.7319793808065, 7669.578173200527, 7366.631668416665, 7367.2264412235945, 7367.631618419165, 7372.852542949218, 7376.520526165782, 7011.360141379279, 7119.288071193047, 7246.818829529304, 7248.130224373109, 7269.527480713737, 7581.355584775135, 7638.656260468181, 7653.469306138683, 7660.310572048543, 7662.616869156408, 4684.110019096361, 4697.13103075932, 4702.365180700562, 4707.411573553306, 4708.011317623337, 6556.0493728410065, 6735.663216839236, 7337.926620733724, 7338.15611204697, 7353.154387245535, 6809.250982392032, 7117.679704413417, 7124.145102587722, 7137.678804453916, 7147.249538798351, 7056.0, 7105.756492613876, 7106.6446677666945, 7114.253003434772, 7120.145582529923, 6949.409300209539, 6956.026156338015, 6960.164780226405, 6961.303869612998, 6967.7212911482975, 7052.0, 7065.717371305091, 7083.929160708327, 7099.680514376762, 7102.609356485236, 6661.233958094687, 6671.499637527108, 6968.895466568107, 6992.895106573507, 7001.934429343458], "value": 5172.458813520526, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [678.7895752316781, 722.9096362954629, 723.8877973914042, 757.8901059346395, 787.2678409079554, 15420.0, 15427.228638568069, 15618.375264989416, 15623.984441011326, 15628.062316261032, 14403.495877644285, 14438.422463101464, 14471.276436178201, 14480.0, 14510.27448627567, 772.7643068863994, 789.5341748368461, 944.6268723854073, 947.1854205383365, 972.2610815779988, 952.8951815300311, 962.3888830592554, 965.9420434773936, 967.4582233949011, 969.9515024248794, 1011.5397494140185, 1022.0801278849058, 1022.5347466902579, 1024.605526872156, 1025.523131743739, 809.0251247247082, 1000.5847573257126, 1014.4572653630272, 1020.8519764634086, 1026.1688032693542, 769.4844454215634, 770.8342706318113, 771.3443572962992, 771.6026246483066, 772.0388116794627, 15304.234788260543, 15335.616609584738, 15346.923265383713, 15370.923145384313, 15395.846041539553, 12684.04869634781, 12862.356882155855, 13052.673683157996, 13270.071095023352, 13330.666733331744, 10236.0, 10255.589776408935, 10394.064534191883, 10602.04581587653, 10975.664567550384, 3546.8226588670464, 3582.5342705448384, 3598.5321908152036, 3653.9086522836874, 3827.3876179811095, 87064.0, 87331.56334218352, 88035.0, 89189.21621567532, 89281.55359223162, 9565.852097748315, 9803.411795292306, 9860.408375497349, 9861.161801246973, 9960.800783984425, 22624.0, 22624.886875565517, 22641.88679056627, 22693.0, 22693.0, 7554.848903021909, 7581.3176814087, 7648.34989025939, 7661.808454788564, 7871.803204919923, 4097.44684467599, 4214.452121224222, 4289.691644048533, 4328.091100868853, 4464.374987501752, 3688.428293614494, 3755.455458958436, 3756.1173124315765, 3759.981200094037, 3765.755225910307, 581.9185314056035, 617.5584457113185, 633.5121956093874, 635.2377147423018, 645.6190847400056, 596.5168213746879, 600.5735927491443, 605.452065880379, 620.9223847019123, 628.4532456762646, 7627.656755446018, 7730.884036739453, 8109.310708589718, 8171.795705107301, 8452.070272270046, 552.8452033430646, 572.9083346664513, 608.191105829246, 611.6116266171014, 641.5733537197711, 3712.2389910068373, 3859.1702783901596, 3898.3957486589625, 3921.490206273166, 3968.583298753647, 93005.0, 94079.0, 94217.05782942219, 94333.5283323579, 95068.0, 59000.0, 77327.61336193395, 77376.0, 78539.0, 78966.6051669738, 66749.33250667529, 67134.66432667806, 70678.7592744437, 71787.64106179436, 72765.0, 6450.580712253689, 7764.417668674759, 7809.219078092373, 7856.0, 7877.7636670899965, 41626.791866040476, 46883.76558117324, 48176.795580110775, 48705.5129448701, 50334.49665503445, 71041.28958710347, 71673.0, 72266.63866680634, 72342.0, 72989.27010729825, 11099.0, 11213.0, 11492.0, 11764.88235117672, 11769.17615766888, 9969.900300996896, 10920.126389888948, 12128.090393220367, 12221.633350999484, 12816.615501534969, 10471.3193642413, 10808.675739727822, 10839.132869370274, 10945.83581246266, 11566.132540059361, 11234.269772464764, 11594.594189203337, 11663.88336116628, 11732.296062236293, 11781.587644432404, 11408.828867566826, 11497.770044599229, 11698.35659038761, 11809.704757381134, 11954.462049207632, 9123.908760912305, 9317.191379877566, 9347.672831450867, 9375.671851485165, 9642.084002019797, 9223.821226154163, 9391.718248452557, 9424.62301507932, 9463.19562837139, 9664.613415463302], "value": 7029.344798358007, "unit": "score"}], "name": "dromaeo_css", "value": 7580.224326450409, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 118.0, 114.0, 112.0, 112.0, 113.0, 114.0, 114.0, 135.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 121.0, 142.0, 165.0, 117.0, 140.0, 121.0, 178.0, 165.0, 111.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 241.0, 258.0, 185.0, 183.0, 184.0, 184.0, 186.0, 186.0, 185.0], "value": 197.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 78.0, 75.0, 152.0, 56.0, 56.0, 59.0, 63.0, 129.0, 59.0], "value": 79.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 139.0, 79.0, 86.0, 86.0, 126.0, 80.0, 79.0, 128.0], "value": 98.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 85.0, 87.0, 83.0, 89.0, 85.0, 88.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 93.0, 94.0, 93.0, 92.0, 93.0, 116.0, 150.0], "value": 101.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 85.0, 86.0, 86.0, 84.0, 86.0, 84.0, 84.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 62.0, 62.0, 62.0, 63.0, 63.0, 77.0, 58.0, 57.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 64.0, 75.0, 76.0, 73.0, 68.0, 66.0, 68.0, 67.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 78.0, 80.0, 80.0, 79.0, 78.0, 77.0, 77.0, 83.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 127.0, 123.0, 126.0, 141.0, 111.0, 136.0, 142.0, 146.0, 167.0], "value": 134.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 217.0, 171.0, 165.0, 163.0, 163.0, 163.0, 162.0, 162.0, 160.0], "value": 171.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 64.0, 66.0, 65.0, 69.0, 66.0, 67.0, 65.0, 67.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1487.5999999999997, "alertThreshold": 2.0}]} 23:17:11 INFO - Return code: 0 23:17:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:17:11 ERROR - # TBPL SUCCESS # 23:17:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:17:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:17:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:17:11 INFO - 2016-07-13 23:17:11.957 screenresolution[1691:10924] starting screenresolution argv=screenresolution get 23:17:11 INFO - 2016-07-13 23:17:11.960 screenresolution[1691:10924] Display 0: 1600x1200x32@60 23:17:11 INFO - 2016-07-13 23:17:11.966 screenresolution[1692:10926] starting screenresolution argv=screenresolution list 23:17:11 INFO - Available Modes on Display 0 23:17:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:17:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:17:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:17:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:17:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:17:12 INFO - Intel Iris: 23:17:12 INFO - Chipset Model: Intel Iris 23:17:12 INFO - Type: GPU 23:17:12 INFO - Bus: Built-In 23:17:12 INFO - VRAM (Dynamic, Max): 1536 MB 23:17:12 INFO - Vendor: Intel (0x8086) 23:17:12 INFO - Device ID: 0x0a2e 23:17:12 INFO - Revision ID: 0x0009 23:17:12 INFO - Displays: 23:17:12 INFO - Display: 23:17:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:17:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:17:12 INFO - Display Serial Number: 1600x1200 60 23:17:12 INFO - Main Display: Yes 23:17:12 INFO - Mirror: Off 23:17:12 INFO - Online: Yes 23:17:12 INFO - Rotation: Supported 23:17:12 INFO - Return code: 0 23:17:12 INFO - Running post-action listener: _resource_record_post_action 23:17:12 INFO - Running post-run listener: _resource_record_post_run 23:17:13 INFO - Total resource usage - Wall time: 1195s; CPU: 52.0%; Read bytes: 33504768; Write bytes: 493944320; Read time: 332; Write time: 1604 23:17:13 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 180904448; Write bytes: 177899008; Read time: 16370; Write time: 386 23:17:13 INFO - run-tests - Wall time: 1174s; CPU: 52.0%; Read bytes: 32583168; Write bytes: 296724480; Read time: 319; Write time: 1163 23:17:13 INFO - Running post-run listener: _upload_blobber_files 23:17:13 INFO - Blob upload gear active. 23:17:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:17:13 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 23:17:13 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'] 23:17:13 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 23:17:13 INFO - (blobuploader) - INFO - Open directory for files ... 23:17:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:17:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:17:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:17:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:17:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:17:14 INFO - (blobuploader) - INFO - Done attempting. 23:17:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:17:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:17:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:17:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:17:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:17:15 INFO - (blobuploader) - INFO - Done attempting. 23:17:15 INFO - (blobuploader) - INFO - Iteration through files over. 23:17:15 INFO - Return code: 0 23:17:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:17:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:17:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8d6ec113e07350dfdfebac0afbe2124bc8df258be3ce5f6651b1e171e090e60e8c8eefb865e5d5686c4e13f2dacae4aab6a220caf06eb253984ccd5bdd1c280f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e29fd0685f68e2766f85124263c6d113627bb4f22bad2e2fb710d24cdbfb260edc6d534d0a90f71c9b9961de5ac803bd7eb1edf20fdbb81d529d3f3d54071d31"} 23:17:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:17:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:17:15 INFO - Contents: 23:17:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8d6ec113e07350dfdfebac0afbe2124bc8df258be3ce5f6651b1e171e090e60e8c8eefb865e5d5686c4e13f2dacae4aab6a220caf06eb253984ccd5bdd1c280f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e29fd0685f68e2766f85124263c6d113627bb4f22bad2e2fb710d24cdbfb260edc6d534d0a90f71c9b9961de5ac803bd7eb1edf20fdbb81d529d3f3d54071d31"} 23:17:15 INFO - Running post-run listener: copy_logs_to_upload_dir 23:17:15 INFO - Copying logs to upload dir... 23:17:15 INFO - mkdir: /builds/slave/test/build/upload/logs 23:17:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.222290 ========= master_lag: 0.75 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-07-13 23:17:16.756616) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 23:17:16.760196) ========= 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.YIIkTArXaO/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.hEgJoD2e7w/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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/8d6ec113e07350dfdfebac0afbe2124bc8df258be3ce5f6651b1e171e090e60e8c8eefb865e5d5686c4e13f2dacae4aab6a220caf06eb253984ccd5bdd1c280f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e29fd0685f68e2766f85124263c6d113627bb4f22bad2e2fb710d24cdbfb260edc6d534d0a90f71c9b9961de5ac803bd7eb1edf20fdbb81d529d3f3d54071d31"} build_url:https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011109 build_url: 'https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8d6ec113e07350dfdfebac0afbe2124bc8df258be3ce5f6651b1e171e090e60e8c8eefb865e5d5686c4e13f2dacae4aab6a220caf06eb253984ccd5bdd1c280f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e29fd0685f68e2766f85124263c6d113627bb4f22bad2e2fb710d24cdbfb260edc6d534d0a90f71c9b9961de5ac803bd7eb1edf20fdbb81d529d3f3d54071d31"}' ========= master_lag: 0.07 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 23:17:16.836705) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-07-13 23:17:16.837084) ========= 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.YIIkTArXaO/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.hEgJoD2e7w/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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.005110 ========= master_lag: 4.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-07-13 23:17:20.848330) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 23:17:20.848839) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 23:17:20.851411) ========= ========= Total master_lag: 4.91 =========