builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0229 starttime: 1468601729.89 results: success (0) buildid: 20160714105543 builduid: aa8f78178def422782ab8d456f54c137 revision: 0b4087792d5fa736a0dc18df7aeefecf0a34d99f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 09:55:29.886738) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 09:55:29.887190) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 09:55:29.887459) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 09:55:29.912478) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 09:55:29.912865) ========= 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.81jkyUMYeO/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.gvepObAYpo/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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-15 09:55:29-- 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% 148M=0s 2016-07-15 09:55:30 (148 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.601136 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 09:55:30.537089) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 09:55:30.537406) ========= 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.81jkyUMYeO/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.gvepObAYpo/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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.026689 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 09:55:30.586453) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 09:55:30.586820) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0b4087792d5fa736a0dc18df7aeefecf0a34d99f --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 0b4087792d5fa736a0dc18df7aeefecf0a34d99f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.81jkyUMYeO/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.gvepObAYpo/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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-15 09:55:30,669 truncating revision to first 12 chars 2016-07-15 09:55:30,670 Setting DEBUG logging. 2016-07-15 09:55:30,670 attempt 1/10 2016-07-15 09:55:30,670 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0b4087792d5f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-15 09:55:31,042 unpacking tar archive at: mozilla-beta-0b4087792d5f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.659075 ========= master_lag: 0.16 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 09:55:31.405161) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 09:55:31.405506) ========= script_repo_revision: 0b4087792d5fa736a0dc18df7aeefecf0a34d99f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 09:55:31.405933) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 09:55:31.406202) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 09:55:31.455158) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-07-15 09:55:31.455549) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.81jkyUMYeO/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.gvepObAYpo/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 09:55:31 INFO - MultiFileLogger online at 20160715 09:55:31 in /builds/slave/test 09:55:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:55:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:55:31 INFO - {'append_to_log': False, 09:55:31 INFO - 'base_work_dir': '/builds/slave/test', 09:55:31 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:55:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:55:31 INFO - 'branch': 'Mozilla-Beta', 09:55:31 INFO - 'buildbot_json_path': 'buildprops.json', 09:55:31 INFO - 'config_files': ('talos/mac_config.py',), 09:55:31 INFO - 'default_actions': ('clobber', 09:55:31 INFO - 'read-buildbot-config', 09:55:31 INFO - 'download-and-extract', 09:55:31 INFO - 'populate-webroot', 09:55:31 INFO - 'create-virtualenv', 09:55:31 INFO - 'install', 09:55:31 INFO - 'run-tests'), 09:55:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:55:31 INFO - 'download_minidump_stackwalk': True, 09:55:31 INFO - 'download_symbols': 'ondemand', 09:55:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:55:31 INFO - 'tooltool.py': '/tools/tooltool.py', 09:55:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:55:31 INFO - '/tools/misc-python/virtualenv.py')}, 09:55:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:55:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:55:31 INFO - 'installer_path': 'installer.exe', 09:55:31 INFO - 'log_level': 'info', 09:55:31 INFO - 'log_name': 'talos', 09:55:31 INFO - 'log_to_console': True, 09:55:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:55:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:55:31 INFO - 'opt_config_files': (), 09:55:31 INFO - 'pip_index': False, 09:55:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:55:31 INFO - 'cmd': ('bash', 09:55:31 INFO - '-c', 09:55:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:55:31 INFO - 'enabled': True, 09:55:31 INFO - 'halt_on_failure': False, 09:55:31 INFO - 'name': 'check_screen_resolution'},), 09:55:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:55:31 INFO - 'cmd': ('bash', 09:55:31 INFO - '-c', 09:55:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:55:31 INFO - 'enabled': True, 09:55:31 INFO - 'halt_on_failure': False, 09:55:31 INFO - 'name': 'check_screen_resolution'},), 09:55:31 INFO - 'run_cmd_checks_enabled': True, 09:55:31 INFO - 'sps_profile': False, 09:55:31 INFO - 'sps_profile_interval': 0, 09:55:31 INFO - 'suite': 'dromaeojs-e10s', 09:55:31 INFO - 'system_bits': '32', 09:55:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:55:31 INFO - 'title': 't-yosemite-r7-0229', 09:55:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:55:31 INFO - 'use_talos_json': True, 09:55:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:55:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:55:31 INFO - 'work_dir': 'build'} 09:55:31 INFO - ##### 09:55:31 INFO - ##### Running clobber step. 09:55:31 INFO - ##### 09:55:31 INFO - Running pre-action listener: _resource_record_pre_action 09:55:31 INFO - Running main action method: clobber 09:55:31 INFO - rmtree: /builds/slave/test/build 09:55:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:55:33 INFO - Running post-action listener: _resource_record_post_action 09:55:33 INFO - ##### 09:55:33 INFO - ##### Running read-buildbot-config step. 09:55:33 INFO - ##### 09:55:33 INFO - Running pre-action listener: _resource_record_pre_action 09:55:33 INFO - Running main action method: read_buildbot_config 09:55:33 INFO - Using buildbot properties: 09:55:33 INFO - { 09:55:33 INFO - "project": "", 09:55:33 INFO - "product": "firefox", 09:55:33 INFO - "script_repo_revision": "production", 09:55:33 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 09:55:33 INFO - "repository": "", 09:55:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 09:55:33 INFO - "buildid": "20160714105543", 09:55:33 INFO - "pgo_build": "False", 09:55:33 INFO - "basedir": "/builds/slave/test", 09:55:33 INFO - "buildnumber": 49, 09:55:33 INFO - "slavename": "t-yosemite-r7-0229", 09:55:33 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:55:33 INFO - "platform": "yosemite_r7", 09:55:33 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:55:33 INFO - "branch": "mozilla-beta", 09:55:33 INFO - "repo_path": "releases/mozilla-beta", 09:55:33 INFO - "revision": "0b4087792d5fa736a0dc18df7aeefecf0a34d99f", 09:55:33 INFO - "stage_platform": "macosx64", 09:55:33 INFO - "builduid": "aa8f78178def422782ab8d456f54c137", 09:55:33 INFO - "slavebuilddir": "test" 09:55:33 INFO - } 09:55:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 09:55:33 INFO - Running post-action listener: _resource_record_post_action 09:55:33 INFO - ##### 09:55:33 INFO - ##### Running download-and-extract step. 09:55:33 INFO - ##### 09:55:33 INFO - Running pre-action listener: _resource_record_pre_action 09:55:33 INFO - Running main action method: download_and_extract 09:55:33 INFO - mkdir: /builds/slave/test/build/tests 09:55:33 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')]} 09:55:33 INFO - https://queue.taskcluster.net/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:55:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 09:55:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 09:55:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/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 09:55:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/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 09:55:35 INFO - Downloaded 1165 bytes. 09:55:35 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 09:55:35 INFO - Using the following test package requirements: 09:55:35 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 09:55:35 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:55:35 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 09:55:35 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:55:35 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 09:55:35 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:55:35 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:55:35 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 09:55:35 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 09:55:35 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:55:35 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 09:55:35 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:55:35 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 09:55:35 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:55:35 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 09:55:35 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:55:35 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 09:55:35 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 09:55:35 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')]} 09:55:35 INFO - https://queue.taskcluster.net/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:55:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:55:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:55:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/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 09:55:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/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 09:55:36 INFO - Downloaded 18314880 bytes. 09:55:36 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'] 09:55:36 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 09:55:39 INFO - Return code: 0 09:55:39 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 09:55:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:39 INFO - https://queue.taskcluster.net/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:55:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:55:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:55:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/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 09:55:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/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 09:55:50 INFO - Downloaded 18314880 bytes. 09:55:50 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'] 09:55:50 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 09:55:53 INFO - Return code: 0 09:55: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')]} 09:55:53 INFO - https://queue.taskcluster.net/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:55:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 09:55:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 09:55:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/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 09:55:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/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 09:55:55 INFO - Downloaded 10840145 bytes. 09:55:55 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'] 09:55:55 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 09:55:55 INFO - Return code: 0 09:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:55:55 INFO - https://queue.taskcluster.net/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 09:55:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:55:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:55:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 09:55:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TunlMASUTGe43JL3Z7bYVQ/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 09:55:57 INFO - Downloaded 86190290 bytes. 09:55:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:55:57 INFO - mkdir: /builds/slave/test/properties 09:55:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:55:57 INFO - Writing to file /builds/slave/test/properties/build_url 09:55:57 INFO - Contents: 09:55:57 INFO - build_url:https://queue.taskcluster.net/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:55:58 INFO - Running post-action listener: _resource_record_post_action 09:55:58 INFO - Running post-action listener: set_extra_try_arguments 09:55:58 INFO - ##### 09:55:58 INFO - ##### Running populate-webroot step. 09:55:58 INFO - ##### 09:55:58 INFO - Running pre-action listener: _resource_record_pre_action 09:55:58 INFO - Running main action method: populate_webroot 09:55:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:55:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:55:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:55:58 INFO - '--tppagecycles', 09:55:58 INFO - '7'], 09:55:58 INFO - 'tests': ['tsvgm']}}, 09:55:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:55:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:55:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:55:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:55:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:58 INFO - 'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:55:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:55:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:58 INFO - 'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['damp', 'tps']}, 09:55:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:58 INFO - 'tests': ['damp', 'tps']}, 09:55:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['dromaeo_dom']}, 09:55:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:55:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['a11yr', 09:55:58 INFO - 'ts_paint', 09:55:58 INFO - 'tpaint', 09:55:58 INFO - 'sessionrestore', 09:55:58 INFO - 'sessionrestore_no_auto_restore', 09:55:58 INFO - 'tabpaint']}, 09:55:58 INFO - 'other-e10s': {'tests': ['a11yr', 09:55:58 INFO - 'ts_paint', 09:55:58 INFO - 'tpaint', 09:55:58 INFO - 'sessionrestore', 09:55:58 INFO - 'sessionrestore_no_auto_restore', 09:55:58 INFO - 'tabpaint']}, 09:55:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:55:58 INFO - 'ts_paint', 09:55:58 INFO - 'tpaint', 09:55:58 INFO - 'sessionrestore', 09:55:58 INFO - 'sessionrestore_no_auto_restore', 09:55:58 INFO - 'tabpaint']}, 09:55:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:55:58 INFO - 'ts_paint', 09:55:58 INFO - 'tpaint', 09:55:58 INFO - 'sessionrestore', 09:55:58 INFO - 'sessionrestore_no_auto_restore', 09:55:58 INFO - 'tabpaint']}, 09:55:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['a11yr', 09:55:58 INFO - 'ts_paint', 09:55:58 INFO - 'tpaint', 09:55:58 INFO - 'sessionrestore', 09:55:58 INFO - 'sessionrestore_no_auto_restore', 09:55:58 INFO - 'tabpaint']}, 09:55:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['a11yr', 09:55:58 INFO - 'ts_paint', 09:55:58 INFO - 'tpaint', 09:55:58 INFO - 'sessionrestore', 09:55:58 INFO - 'sessionrestore_no_auto_restore', 09:55:58 INFO - 'tabpaint']}, 09:55:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['tsvgx', 09:55:58 INFO - 'tsvgr_opacity', 09:55:58 INFO - 'tart', 09:55:58 INFO - 'tscrollx', 09:55:58 INFO - 'cart']}, 09:55:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:55:58 INFO - 'tsvgr_opacity', 09:55:58 INFO - 'tart', 09:55:58 INFO - 'tscrollx', 09:55:58 INFO - 'cart']}, 09:55:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:58 INFO - 'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['tp5o']}, 09:55:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:58 INFO - 'tests': ['tp5o']}, 09:55:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:58 INFO - 'talos_options': ['--disable-e10s', 09:55:58 INFO - '--xperf_path', 09:55:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:55:58 INFO - 'tests': ['tp5n']}, 09:55:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:58 INFO - 'talos_options': ['--xperf_path', 09:55:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:55:58 INFO - 'tests': ['tp5n']}}, 09:55:58 INFO - 'talos.zip': {'path': '', 09:55:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:55:58 INFO - Running post-action listener: _resource_record_post_action 09:55:58 INFO - ##### 09:55:58 INFO - ##### Running create-virtualenv step. 09:55:58 INFO - ##### 09:55:58 INFO - Running pre-action listener: _resource_record_pre_action 09:55:58 INFO - Running main action method: create_virtualenv 09:55:58 INFO - Creating virtualenv /builds/slave/test/build/venv 09:55:58 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 09:55:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:55:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:55:58 INFO - Using real prefix '/tools/python27' 09:55:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:55:59 INFO - Installing distribute.............................................................................................................................................................................................done. 09:56:02 INFO - Installing pip.................done. 09:56:02 INFO - Return code: 0 09:56:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:56: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')]} 09:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56: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')]} 09:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109594a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de1d5a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109433e00>, '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 0x10929cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10942eb20>, '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.81jkyUMYeO/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.gvepObAYpo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 09:56:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:56:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:56:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.81jkyUMYeO/Render', 09:56:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:02 INFO - 'HOME': '/Users/cltbld', 09:56:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:02 INFO - 'LOGNAME': 'cltbld', 09:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:02 INFO - 'MOZ_NO_REMOTE': '1', 09:56:02 INFO - 'NO_EM_RESTART': '1', 09:56:02 INFO - 'PAGER': '/bin/cat', 09:56:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:02 INFO - 'PWD': '/builds/slave/test', 09:56:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:02 INFO - 'SHELL': '/bin/bash', 09:56:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvepObAYpo/Listeners', 09:56:02 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 09:56:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:02 INFO - 'USER': 'cltbld', 09:56:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:02 INFO - 'XPC_FLAGS': '0x0', 09:56:02 INFO - 'XPC_SERVICE_NAME': '0', 09:56:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:02 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 09:56:02 INFO - Cleaning up... 09:56:02 INFO - Return code: 0 09:56:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:56: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')]} 09:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56: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')]} 09:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109594a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de1d5a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109433e00>, '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 0x10929cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10942eb20>, '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.81jkyUMYeO/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.gvepObAYpo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 09:56:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:56:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:56:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.81jkyUMYeO/Render', 09:56:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:02 INFO - 'HOME': '/Users/cltbld', 09:56:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:02 INFO - 'LOGNAME': 'cltbld', 09:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:02 INFO - 'MOZ_NO_REMOTE': '1', 09:56:02 INFO - 'NO_EM_RESTART': '1', 09:56:02 INFO - 'PAGER': '/bin/cat', 09:56:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:02 INFO - 'PWD': '/builds/slave/test', 09:56:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:02 INFO - 'SHELL': '/bin/bash', 09:56:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvepObAYpo/Listeners', 09:56:02 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 09:56:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:02 INFO - 'USER': 'cltbld', 09:56:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:02 INFO - 'XPC_FLAGS': '0x0', 09:56:02 INFO - 'XPC_SERVICE_NAME': '0', 09:56:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:03 INFO - Downloading/unpacking psutil>=0.7.1 09:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:56:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:56:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:56:06 INFO - Installing collected packages: psutil 09:56:06 INFO - Running setup.py install for psutil 09:56:06 INFO - building 'psutil._psutil_osx' extension 09:56:06 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 09:56:06 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 09:56:06 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 09:56:06 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 09:56:06 INFO - building 'psutil._psutil_posix' extension 09:56:06 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 09:56:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:56:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:56:06 INFO - ^ 09:56:06 INFO - 1 warning generated. 09:56:06 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 09:56:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:56:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:56:07 INFO - Successfully installed psutil 09:56:07 INFO - Cleaning up... 09:56:07 INFO - Return code: 0 09:56:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:56: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')]} 09:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56: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')]} 09:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:07 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 0x109594a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de1d5a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109433e00>, '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 0x10929cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10942eb20>, '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.81jkyUMYeO/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.gvepObAYpo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 09:56:07 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 09:56:07 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 09:56:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.81jkyUMYeO/Render', 09:56:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:07 INFO - 'HOME': '/Users/cltbld', 09:56:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:07 INFO - 'LOGNAME': 'cltbld', 09:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:07 INFO - 'MOZ_NO_REMOTE': '1', 09:56:07 INFO - 'NO_EM_RESTART': '1', 09:56:07 INFO - 'PAGER': '/bin/cat', 09:56:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:07 INFO - 'PWD': '/builds/slave/test', 09:56:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:07 INFO - 'SHELL': '/bin/bash', 09:56:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvepObAYpo/Listeners', 09:56:07 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 09:56:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:07 INFO - 'USER': 'cltbld', 09:56:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:07 INFO - 'XPC_FLAGS': '0x0', 09:56:07 INFO - 'XPC_SERVICE_NAME': '0', 09:56:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:56:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:56:10 INFO - Installing collected packages: mozsystemmonitor 09:56:10 INFO - Running setup.py install for mozsystemmonitor 09:56:10 INFO - Successfully installed mozsystemmonitor 09:56:10 INFO - Cleaning up... 09:56:10 INFO - Return code: 0 09:56:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109594a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de1d5a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109433e00>, '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 0x10929cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10942eb20>, '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.81jkyUMYeO/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.gvepObAYpo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 09:56:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:56:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:56:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.81jkyUMYeO/Render', 09:56:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:10 INFO - 'HOME': '/Users/cltbld', 09:56:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:10 INFO - 'LOGNAME': 'cltbld', 09:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:10 INFO - 'MOZ_NO_REMOTE': '1', 09:56:10 INFO - 'NO_EM_RESTART': '1', 09:56:10 INFO - 'PAGER': '/bin/cat', 09:56:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:10 INFO - 'PWD': '/builds/slave/test', 09:56:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:10 INFO - 'SHELL': '/bin/bash', 09:56:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvepObAYpo/Listeners', 09:56:10 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 09:56:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:10 INFO - 'USER': 'cltbld', 09:56:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:10 INFO - 'XPC_FLAGS': '0x0', 09:56:10 INFO - 'XPC_SERVICE_NAME': '0', 09:56:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:10 INFO - Downloading/unpacking blobuploader==1.2.4 09:56: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 09:56: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 09:56: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 09:56: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 09:56: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 09:56: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 09:56:12 INFO - Downloading blobuploader-1.2.4.tar.gz 09:56:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:56:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:56:12 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 09:56:12 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 09:56:12 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 09:56:12 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 09:56:12 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 09:56:12 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 09:56:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:56:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:14 INFO - Downloading docopt-0.6.1.tar.gz 09:56:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:56:14 INFO - Installing collected packages: blobuploader, requests, docopt 09:56:14 INFO - Running setup.py install for blobuploader 09:56:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:56:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:56:14 INFO - Running setup.py install for requests 09:56:14 INFO - Running setup.py install for docopt 09:56:14 INFO - Successfully installed blobuploader requests docopt 09:56:14 INFO - Cleaning up... 09:56:15 INFO - Return code: 0 09:56:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56:15 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')]} 09:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:15 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')]} 09:56:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:15 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 0x109594a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de1d5a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109433e00>, '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 0x10929cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10942eb20>, '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.81jkyUMYeO/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.gvepObAYpo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 09:56:15 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 09:56:15 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 09:56:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.81jkyUMYeO/Render', 09:56:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:15 INFO - 'HOME': '/Users/cltbld', 09:56:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:15 INFO - 'LOGNAME': 'cltbld', 09:56:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:15 INFO - 'MOZ_NO_REMOTE': '1', 09:56:15 INFO - 'NO_EM_RESTART': '1', 09:56:15 INFO - 'PAGER': '/bin/cat', 09:56:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:15 INFO - 'PWD': '/builds/slave/test', 09:56:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:15 INFO - 'SHELL': '/bin/bash', 09:56:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvepObAYpo/Listeners', 09:56:15 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 09:56:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:15 INFO - 'USER': 'cltbld', 09:56:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:15 INFO - 'XPC_FLAGS': '0x0', 09:56:15 INFO - 'XPC_SERVICE_NAME': '0', 09:56:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:56:15 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-hGI0bE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:56:15 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-QJlduW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:56:15 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-s748xi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:56:15 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-UgwZ_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:56:15 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-XpOWXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:56:15 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-kf1UUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:56:15 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-JUaP5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:56:16 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-V0ETz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:56:16 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-N_oPA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:56:16 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-ByTGXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:56:16 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-rXL3xf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:56:16 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-UYrUeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:56:16 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-f5kG7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:56:16 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-EPhhAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:16 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-aApexx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:56:16 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-cG1204-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:56:16 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-_4i1Zb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:56:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:56:17 INFO - Running setup.py install for manifestparser 09:56:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:56:17 INFO - Running setup.py install for mozcrash 09:56:17 INFO - Running setup.py install for mozdebug 09:56:17 INFO - Running setup.py install for mozdevice 09:56:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:56:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:56:17 INFO - Running setup.py install for mozfile 09:56:17 INFO - Running setup.py install for mozhttpd 09:56:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:56:17 INFO - Running setup.py install for mozinfo 09:56:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Running setup.py install for mozInstall 09:56:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Running setup.py install for mozleak 09:56:18 INFO - Running setup.py install for mozlog 09:56:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Running setup.py install for moznetwork 09:56:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Running setup.py install for mozprocess 09:56:18 INFO - Running setup.py install for mozprofile 09:56:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:56:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:56:19 INFO - Running setup.py install for mozrunner 09:56:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:56:19 INFO - Running setup.py install for mozscreenshot 09:56:19 INFO - Running setup.py install for moztest 09:56:19 INFO - Running setup.py install for mozversion 09:56:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:56:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:56:19 INFO - Cleaning up... 09:56:19 INFO - Return code: 0 09:56:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56:19 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')]} 09:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:19 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')]} 09:56:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:19 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 0x109594a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de1d5a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109433e00>, '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 0x10929cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10942eb20>, '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.81jkyUMYeO/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.gvepObAYpo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 09:56:19 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 09:56:19 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 09:56:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.81jkyUMYeO/Render', 09:56:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:19 INFO - 'HOME': '/Users/cltbld', 09:56:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:19 INFO - 'LOGNAME': 'cltbld', 09:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:19 INFO - 'MOZ_NO_REMOTE': '1', 09:56:19 INFO - 'NO_EM_RESTART': '1', 09:56:19 INFO - 'PAGER': '/bin/cat', 09:56:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:19 INFO - 'PWD': '/builds/slave/test', 09:56:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:19 INFO - 'SHELL': '/bin/bash', 09:56:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvepObAYpo/Listeners', 09:56:19 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 09:56:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:19 INFO - 'USER': 'cltbld', 09:56:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:19 INFO - 'XPC_FLAGS': '0x0', 09:56:19 INFO - 'XPC_SERVICE_NAME': '0', 09:56:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:56:19 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-ghqx0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:56:20 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)) 09:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:56:20 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-avAh4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:56:20 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)) 09:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:56:20 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-kZkdGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:56:20 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)) 09:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:56:20 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-0_vhhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:56:20 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)) 09:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:56:20 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-KlVpCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:56:20 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)) 09:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:56:20 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-qYz4Vl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:56:20 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)) 09:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:56:20 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-TPwUX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:56:20 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)) 09:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:56:20 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-e5Mc5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:56:20 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)) 09:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:56:20 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-w8sZHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:56:20 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)) 09:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:56:20 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-pkrJKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:56:20 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)) 09:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:56:20 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-a96HiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:56:21 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)) 09:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:56:21 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-3Qd7ZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:56:21 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)) 09:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:56:21 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-B8YnmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:56:21 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)) 09:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:56:21 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-wJ4FR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:56:21 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)) 09:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:21 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-nosoQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:21 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)) 09:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:56:21 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-rYz8hC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:56:21 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)) 09:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:56:21 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-Ygp9s7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:56:21 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)) 09:56:21 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)) 09:56:21 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)) 09:56:21 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)) 09:56:21 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)) 09:56:21 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)) 09:56:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:56:21 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 09:56:21 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 09:56:21 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 09:56:21 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 09:56:21 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 09:56:21 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 09:56:24 INFO - Downloading blessings-1.6.tar.gz 09:56:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:56:24 INFO - Installing collected packages: blessings 09:56:24 INFO - Running setup.py install for blessings 09:56:24 INFO - Successfully installed blessings 09:56:24 INFO - Cleaning up... 09:56:24 INFO - Return code: 0 09:56:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:56:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:56:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:56:24 INFO - Reading from file tmpfile_stdout 09:56:24 INFO - Current package versions: 09:56:24 INFO - blessings == 1.6 09:56:24 INFO - blobuploader == 1.2.4 09:56:24 INFO - docopt == 0.6.1 09:56:24 INFO - manifestparser == 1.1 09:56:24 INFO - mozInstall == 1.12 09:56:24 INFO - mozcrash == 0.17 09:56:24 INFO - mozdebug == 0.1 09:56:24 INFO - mozdevice == 0.48 09:56:24 INFO - mozfile == 1.2 09:56:24 INFO - mozhttpd == 0.7 09:56:24 INFO - mozinfo == 0.9 09:56:24 INFO - mozleak == 0.1 09:56:24 INFO - mozlog == 3.1 09:56:24 INFO - moznetwork == 0.27 09:56:24 INFO - mozprocess == 0.22 09:56:24 INFO - mozprofile == 0.28 09:56:24 INFO - mozrunner == 6.11 09:56:24 INFO - mozscreenshot == 0.1 09:56:24 INFO - mozsystemmonitor == 0.0 09:56:24 INFO - moztest == 0.7 09:56:24 INFO - mozversion == 1.4 09:56:24 INFO - psutil == 3.1.1 09:56:24 INFO - requests == 1.2.3 09:56:24 INFO - wsgiref == 0.1.2 09:56:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:24 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 0x109594a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de1d5a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109433e00>, '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 0x10929cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10942eb20>, '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.81jkyUMYeO/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.gvepObAYpo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 09:56:24 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 09:56:24 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 09:56:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.81jkyUMYeO/Render', 09:56:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:24 INFO - 'HOME': '/Users/cltbld', 09:56:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:24 INFO - 'LOGNAME': 'cltbld', 09:56:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:24 INFO - 'MOZ_NO_REMOTE': '1', 09:56:24 INFO - 'NO_EM_RESTART': '1', 09:56:24 INFO - 'PAGER': '/bin/cat', 09:56:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:24 INFO - 'PWD': '/builds/slave/test', 09:56:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:24 INFO - 'SHELL': '/bin/bash', 09:56:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvepObAYpo/Listeners', 09:56:24 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 09:56:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:24 INFO - 'USER': 'cltbld', 09:56:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:24 INFO - 'XPC_FLAGS': '0x0', 09:56:24 INFO - 'XPC_SERVICE_NAME': '0', 09:56:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:25 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)) 09:56:25 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)) 09:56:25 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)) 09:56:25 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)) 09:56:25 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)) 09:56:25 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)) 09:56:25 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)) 09:56:25 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)) 09:56:25 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)) 09:56:25 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)) 09:56:25 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)) 09:56:25 INFO - Cleaning up... 09:56:25 INFO - Return code: 0 09:56:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09: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')]} 09:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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')]} 09:56:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:25 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 0x109594a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de1d5a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109433e00>, '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 0x10929cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10942eb20>, '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.81jkyUMYeO/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.gvepObAYpo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 09:56:25 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 09:56:25 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 09:56:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.81jkyUMYeO/Render', 09:56:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:25 INFO - 'HOME': '/Users/cltbld', 09:56:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:25 INFO - 'LOGNAME': 'cltbld', 09:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:25 INFO - 'MOZ_NO_REMOTE': '1', 09:56:25 INFO - 'NO_EM_RESTART': '1', 09:56:25 INFO - 'PAGER': '/bin/cat', 09:56:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:25 INFO - 'PWD': '/builds/slave/test', 09:56:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:25 INFO - 'SHELL': '/bin/bash', 09:56:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvepObAYpo/Listeners', 09:56:25 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 09:56:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:25 INFO - 'USER': 'cltbld', 09:56:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:25 INFO - 'XPC_FLAGS': '0x0', 09:56:25 INFO - 'XPC_SERVICE_NAME': '0', 09:56:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:25 INFO - Downloading/unpacking jsonschema 09:56:25 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 09:56:25 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 09:56:25 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 09:56:25 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 09:56:25 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 09:56:25 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 09:56:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:56:27 INFO - Downloading/unpacking functools32 (from jsonschema) 09:56:27 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 09:56:27 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 09:56:27 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 09:56:27 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 09:56:27 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 09:56:27 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 09:56:28 INFO - Downloading functools32-3.2.3-2.tar.gz 09:56:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:56:28 INFO - warning: no files found matching '*.txt' 09:56:28 INFO - no previously-included directories found matching 'build' 09:56:28 INFO - no previously-included directories found matching 'dist' 09:56:28 INFO - no previously-included directories found matching '.git*' 09:56:28 INFO - Installing collected packages: jsonschema, functools32 09:56:28 INFO - Running setup.py install for functools32 09:56:28 INFO - warning: no files found matching '*.txt' 09:56:28 INFO - no previously-included directories found matching 'build' 09:56:28 INFO - no previously-included directories found matching 'dist' 09:56:28 INFO - no previously-included directories found matching '.git*' 09:56:28 INFO - Successfully installed jsonschema functools32 09:56:28 INFO - Cleaning up... 09:56:28 INFO - Return code: 0 09:56:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:56:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:28 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 0x109594a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9de1d5a5e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109433e00>, '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 0x10929cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10942eb20>, '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.81jkyUMYeO/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.gvepObAYpo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 09:56:28 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 09:56:28 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 09:56:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.81jkyUMYeO/Render', 09:56:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:28 INFO - 'HOME': '/Users/cltbld', 09:56:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:28 INFO - 'LOGNAME': 'cltbld', 09:56:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:28 INFO - 'MOZ_NO_REMOTE': '1', 09:56:28 INFO - 'NO_EM_RESTART': '1', 09:56:28 INFO - 'PAGER': '/bin/cat', 09:56:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:28 INFO - 'PWD': '/builds/slave/test', 09:56:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:28 INFO - 'SHELL': '/bin/bash', 09:56:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvepObAYpo/Listeners', 09:56:28 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 09:56:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:28 INFO - 'USER': 'cltbld', 09:56:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:28 INFO - 'XPC_FLAGS': '0x0', 09:56:28 INFO - 'XPC_SERVICE_NAME': '0', 09:56:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:28 INFO - Downloading/unpacking flake8 09:56:28 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 09:56:28 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 09:56:28 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 09:56:28 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 09:56:28 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 09:56:28 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 09:56:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:56:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:56:31 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 09:56:31 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 09:56:31 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 09:56:31 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 09:56:31 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 09:56:31 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 09:56:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:56:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:56:31 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 09:56:31 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 09:56:31 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 09:56:31 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 09:56:31 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 09:56:31 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 09:56:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:56:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:56:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:56:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:56:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:56:32 INFO - no previously-included directories found matching 'docs/_build' 09:56:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:56:32 INFO - Running setup.py install for pep8 09:56:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:56:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:56:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:56:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:56:32 INFO - no previously-included directories found matching 'docs/_build' 09:56:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:56:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:56:32 INFO - Cleaning up... 09:56:32 INFO - Return code: 0 09:56:32 INFO - Running post-action listener: _resource_record_post_action 09:56:32 INFO - Running post-action listener: _start_resource_monitoring 09:56:32 INFO - Starting resource monitoring. 09:56:32 INFO - ##### 09:56:32 INFO - ##### Running install step. 09:56:32 INFO - ##### 09:56:32 INFO - Running pre-action listener: _resource_record_pre_action 09:56:32 INFO - Running main action method: install 09:56:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:56:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:56:33 INFO - Reading from file tmpfile_stdout 09:56:33 INFO - Detecting whether we're running mozinstall >=1.0... 09:56:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:56:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:56:33 INFO - Reading from file tmpfile_stdout 09:56:33 INFO - Output received: 09:56:33 INFO - Usage: mozinstall [options] installer 09:56:33 INFO - Options: 09:56:33 INFO - -h, --help show this help message and exit 09:56:33 INFO - -d DEST, --destination=DEST 09:56:33 INFO - Directory to install application into. [default: 09:56:33 INFO - "/builds/slave/test"] 09:56:33 INFO - --app=APP Application being installed. [default: firefox] 09:56:33 INFO - mkdir: /builds/slave/test/build/application 09:56:33 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'] 09:56:33 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 09:56:55 INFO - Reading from file tmpfile_stdout 09:56:55 INFO - Output received: 09:56:55 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:56:55 INFO - Running post-action listener: _resource_record_post_action 09:56:55 INFO - ##### 09:56:55 INFO - ##### Running run-tests step. 09:56:55 INFO - ##### 09:56:55 INFO - Running pre-action listener: _resource_record_pre_action 09:56:55 INFO - Running main action method: run_tests 09:56:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:56:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:56:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:56:55 INFO - 2016-07-15 09:56:55.550 screenresolution[1891:14622] starting screenresolution argv=screenresolution get 09:56:55 INFO - 2016-07-15 09:56:55.553 screenresolution[1891:14622] Display 0: 1600x1200x32@60 09:56:55 INFO - 2016-07-15 09:56:55.559 screenresolution[1892:14625] starting screenresolution argv=screenresolution list 09:56:55 INFO - Available Modes on Display 0 09:56:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:56:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:56:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:56:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:56:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:56:55 INFO - Intel Iris: 09:56:55 INFO - Chipset Model: Intel Iris 09:56:55 INFO - Type: GPU 09:56:55 INFO - Bus: Built-In 09:56:55 INFO - VRAM (Dynamic, Max): 1536 MB 09:56:55 INFO - Vendor: Intel (0x8086) 09:56:55 INFO - Device ID: 0x0a2e 09:56:55 INFO - Revision ID: 0x0009 09:56:55 INFO - Displays: 09:56:55 INFO - Display: 09:56:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:56:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:56:55 INFO - Display Serial Number: 1600x1200 60 09:56:55 INFO - Main Display: Yes 09:56:55 INFO - Mirror: Off 09:56:55 INFO - Online: Yes 09:56:55 INFO - Rotation: Supported 09:56:55 INFO - Return code: 0 09:56:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:56:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:56:55 INFO - Python 2.7.3 09:56:55 INFO - Return code: 0 09:56:55 INFO - grabbing minidump binary from tooltool 09:56:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:55 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 0x109433e00>, '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 0x10929cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10942eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:56:55 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 09:56:55 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 09:56:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:56:55 INFO - Return code: 0 09:56:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:56:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:56:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:56:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:56:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:56:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:56:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:56:56 INFO - Return code: 0 09:56:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0229', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:56:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0229 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:56:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.81jkyUMYeO/Render', 09:56:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:56 INFO - 'HOME': '/Users/cltbld', 09:56:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:56 INFO - 'LOGNAME': 'cltbld', 09:56:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:56:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:56:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:56 INFO - 'MOZ_NO_REMOTE': '1', 09:56:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:56:56 INFO - 'NO_EM_RESTART': '1', 09:56:56 INFO - 'PAGER': '/bin/cat', 09:56:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:56 INFO - 'PWD': '/builds/slave/test', 09:56:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:56:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:56 INFO - 'SHELL': '/bin/bash', 09:56:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvepObAYpo/Listeners', 09:56:56 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 09:56:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:56 INFO - 'USER': 'cltbld', 09:56:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:56 INFO - 'XPC_FLAGS': '0x0', 09:56:56 INFO - 'XPC_SERVICE_NAME': '0', 09:56:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0229', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:56:57 INFO - mozversion application_buildid: 20160714105543 09:56:57 INFO - mozversion application_changeset: 0b4087792d5fa736a0dc18df7aeefecf0a34d99f 09:56:57 INFO - mozversion application_display_name: Firefox 09:56:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:56:57 INFO - mozversion application_name: Firefox 09:56:57 INFO - mozversion application_remotingname: firefox 09:56:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:56:57 INFO - mozversion application_vendor: Mozilla 09:56:57 INFO - mozversion application_version: 48.0 09:56:57 INFO - mozversion platform_buildid: 20160714105543 09:56:57 INFO - mozversion platform_changeset: 0b4087792d5fa736a0dc18df7aeefecf0a34d99f 09:56:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:56:57 INFO - mozversion platform_version: 48.0 09:56:57 INFO - using testdate: 1468601817 09:56:57 INFO - actual date: 1468601817 09:56:57 INFO - starting webserver on 'localhost:49294' 09:56:57 INFO - SUITE-START | Running 2 tests 09:56:57 INFO - TEST-START | dromaeo_css 09:56:57 INFO - operating with platform_type : mac_ 09:56:57 INFO - Initialising browser for dromaeo_css test... 09:56:57 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmp5IJtcN/profile http://localhost:49294/getInfo.html) 09:56:59 INFO - PROCESS | 1910 | __metrics Screen width/height:1600/1200 09:56:59 INFO - PROCESS | 1910 | colorDepth:24 09:56:59 INFO - PROCESS | 1910 | Browser inner width/height: 1024/689 09:56:59 INFO - PROCESS | 1910 | __metrics 09:57:00 ERROR - PROCESS | 1910 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 09:57:00 INFO - TEST-INFO | 1910: exit 0 09:57:00 INFO - Browser initialized. 09:57:00 INFO - Running cycle 1/1 for dromaeo_css test... 09:57:00 INFO - TEST-INFO | started process 1915 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmp5IJtcN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:12 INFO - PROCESS | 1915 | RSS: Main: 170749952 09:57:12 INFO - PROCESS | 1915 | 09:58:02 INFO - PROCESS | 1915 | 1468601882226 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:00:12 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49294/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49294/tests/dromaeo/cssquery-ext.html) 10:00:14 INFO - PROCESS | 1915 | RSS: Main: 181628928 10:00:14 INFO - PROCESS | 1915 | 10:03:16 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49294/tests/dromaeo/cssquery-ext.html (next: http://localhost:49294/tests/dromaeo/cssquery-jquery.html) 10:03:16 INFO - PROCESS | 1915 | RSS: Main: 192380928 10:03:16 INFO - PROCESS | 1915 | 10:06:18 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49294/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49294/tests/dromaeo/cssquery-mootools.html) 10:06:19 INFO - PROCESS | 1915 | RSS: Main: 194277376 10:06:19 INFO - PROCESS | 1915 | 10:09:20 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49294/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49294/tests/dromaeo/cssquery-prototype.html) 10:09:21 INFO - PROCESS | 1915 | RSS: Main: 198316032 10:09:21 INFO - PROCESS | 1915 | 10:12:23 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49294/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49294/tests/dromaeo/cssquery-yui.html) 10:12:24 INFO - PROCESS | 1915 | RSS: Main: 197246976 10:12:24 INFO - PROCESS | 1915 | 10:15:26 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49294/tests/dromaeo/cssquery-yui.html (next: http://localhost:49294/tests/dromaeo/cssquery-dojo.html) 10:15:26 INFO - PROCESS | 1915 | RSS: Main: 202268672 10:15:26 INFO - PROCESS | 1915 | 10:15:26 INFO - PROCESS | 1915 | __start_tp_report 10:15:26 INFO - PROCESS | 1915 | _x_x_mozilla_page_load 10:15:26 INFO - PROCESS | 1915 | _x_x_mozilla_page_load_details 10:15:26 INFO - PROCESS | 1915 | |i|pagename|runs| 10:15:26 INFO - PROCESS | 1915 | |0;dojo.html;2991.8055326403783;3668.8349024293902;3713.4801127842125;3744.3073031489175;3777.1690228149796;5925.703714814258;6036.577439579231;6045.939540604598;6136.877262454748;6176.567640265178;6162.168107305512;6165.290991535975;6180.227471566054;6196.9465190917535;6208.130861679358;5460.918086228709;5523.226748255227;5569.777208911639;5614.69119198446;5655.26481557399;5480.7533660985155;5566.805161819337;5606.018946684334;5631.521320687746;5683.062294721387;5562.16567514874;5567.3040869891265;5575.275214222166;5581.6930068846195;5581.69300688464;5531.3362396512675;5539.279893613824;5556.91664625031;5599.216109744639;5603.803866864681;5400.78396864125;5550.972245138789;5555.444455554412;5578.219049333096;5580.832575022756;6117.847053823645;6120.418560236771;6123.571350005502;6148.569600127993;6172.506199504029;6261.154744109533;6354.7458101675875;6396.808095757088;6401.039844023434;6411.3909178627955;6509;6530.216374035099;6537.509686773511;6538.411542961157;6552.705128269239;6203.503719702413;6220.626762394271;6224;6234.220722409699;6235.376462353774;6428.100065990765;6543.901841472381;6593.571417857824;6639.668016599151;6656.201255849329;6310.684465776692;6332.968335158294;6334.018227174795;6349.428551430394;6354.205724284465;6526.216853977553;6533.8366540835905;6550;6555;6567.868642627216;6181.96909015461;6271.216097987752;6281.748730050838;6368.490520758328;6373.553851230369;6314.17915670969;6319.526035547352;6353.364663533702;6368.554201205871;6376.33048529907;5076.0863044652315;5095.923561146586;5137.948620513747;5185.066687996122;5215.973920130375;4641.327007583949;4648;4666.510016448292;4685.01614660925;4722.787474563653;6182.227221597301;6209.627422354673;6223.097650840693;6232.3144454110015;6249.78125765596;6352.2694890087305;6411.4870810336;6506.674666266667;6520.380563846427;6521.47828173745;5468.207109969112;6015.248093988252;6127.708789264043;6169.784057557964;6178.011518157074;5679.971600142056;5719.28508936383;5719.370869204384;5738.99567575681;5739.598228123991;6651.168603924412;6692.498062645223;6787.5588086774205;6802.523823332418;6808.387245147861;6401.263854656774;6466.870662586628;6472.902906456501;6490.383413575702;6512.706928188338;6665.800025999228;6728.024436456784;6731.225909020525;6735.360140786617;6744.224414192235;6292.433680968735;6388.55280130405;6420.7431702731365;6426.4537514311805;6445.677716114082;6514.446272066738;6521.804345869631;6559.344065593594;6561.507886908405;6582.407583317525;6349.333320001333;6355.428011478989;6356.618602883842;6362.427381535684;6369.936300636934;6199.876002480015;6290;6298.212723409574;6301.149344838388;6340.587861788969;5429.212764149255;5486.1770734388565;5486.369067557282;6203.348648391854;6220.782272620437;5516.779328826802;6275.507658534188;6304.747810087545;6320.936790632035;6325.8734825304155;6091.634501929899;6160.168377269011;6207.16203312547;6230.59501132425;6237.345078766664 10:15:26 INFO - PROCESS | 1915 | |1;ext.html;525.187756837183;545.7135004122838;558.5894367639785;593.0215145010729;602.9779523707315;30811.22971925703;39127.60872391275;39159.216815663705;39220.80389598048;39222.01944951378;40024.19951600966;40710;41189.794051029705;41279.38080928781;41294.7935260324;9640.662576809813;9756.317057805956;9826.066523680274;9911.355761875455;9939.652112176076;9797;9867.901320986806;9872.259580531452;9924.05721456464;9988.051135142186;9872.062154095349;9940.701778946606;9965.202783777317;9988.500574971258;10055.798884022315;9880.703578892644;9949.253805964545;9983.500824958723;10020.649277275297;10040.096391324743;9815.920248772629;9827.066428689262;9919.652812151613;9925.2059835213;9939.453330066843;32686.019419417218;35302.94091177269;39384.409233861516;39561.604383956364;40541.79729101365;3142.8114313141286;3355.547001154862;3376;3409.7272218222483;3414.163529935182;28107.718922810716;28199.718002819915;28884.566731499046;29350.266243344086;29501.557476637652;2646.1135519600957;2760.751532362077;2761.240658818821;2788.8047836651444;2966.3770608172094;353261;400685;408325.9583702092;412594;415083.77374339406;37164.25671486609;37895.62104378976;63293.68353158205;64094.35905640977;64326.67836660787;35234.64765352365;35798.28403431917;35876;35893.82053089718;36087;15902.966307189996;15903.363865445484;16091.919540402223;16105.355785768672;16135.596610084842;13104.475820967245;13323.267220302976;13390.866091338961;13432.261225632488;13509.662258443617;8358.791030224293;8362.331013518904;8498.74503764888;8499.277561407349;8521.82956340882;3543.291341731664;3564.9483402396354;3575.606683264839;3586.6951309138494;3618.113562177231;978.3347323819871;1048.9091345001113;1063.7925604507202;1092.6285063078592;1129.8644861913888;29195.56206656902;30521.827695011507;30592;30815.305158216514;30913;1078.6840055132725;1149.40231079838;1167.3054532553117;1199.9740222110145;1233.4141282890735;13609.455621775018;15304.387824486896;15457.38170473191;15601.054769554541;16109.436169734237;623528;626681;628340;629479;639555;61907.690461547405;62407.68796156081;62576.68711656413;62582;62601.06098408528;1503.1356969742442;1526.8167819861465;1547.2727817925781;1548.7212301785787;1622.051100106451;29256.26859328534;29817.5527367094;30936.69063309338;31407.37185256328;34492.482612761334;63960.3603963973;64496.677516612115;64898.35101649112;65006.674966624865;65126.67436662786;574682;586862;587552;589571;593056;41985.58014419939;45611.77194114008;45622.31566526438;47468.762656186496;48838.02323953572;50013.499865000886;50330.2450463236;51476.48523514717;51699.483005169466;52240.955180896926;40590.59409405868;41258.968525787095;41329.58670413258;41514.58485415228;42140.73577792671;40151.397729034674;40730.79634601808;40892.38661420141;41279;42010.579894200666;41304;41549;42045.57954420539;42972.78513607412;43016.569834301255;31932.201694957814;33813.66186338105;33956.830215848764;34221;34479.65520344764;22863;23012.19457318986;26568.070117545794;26903;27072.864635676695 10:15:26 INFO - PROCESS | 1915 | |2;jquery.html;396.3440505962632;415.79625983268187;416.15312838373916;420.42821762403105;427.39736970871076;15529.456469023586;16865.83134168658;20480.487987800312;21084.472888177766;21190.04644790984;20087.799122008775;20634.89682551589;20859.165633374647;21217.893910530463;21233.787662123414;5744.281964754406;5861.296644402667;6044.455998960093;6078.908816367758;6169.907451388232;5808.083332500286;5965.164876917235;6003.459688628022;6012.308584512772;6013.939860601404;5252.6848389096585;6271.247450305947;6328.462080723144;6335.968320158393;6370.713317900682;5823.6505809651135;6210.596311239755;6318.336574659664;6321.178246827929;6343.619382837044;5996.880062398771;6006;6007.729652165619;6008.708127752554;6009.489193418564;14122.858771412257;17563.56091097733;21085.578288434146;26512.60231096535;26896.46207075867;2149.9247526336503;2252.68462415262;2341.976580234193;2346.8005219556358;2577.7937764978756;10154.238432117621;10285.897141028569;10293.279470437072;10425.687229383131;10580;1594.4339759385366;1609.3401705300887;1813.519417354402;1824.616830465604;1847.4088291746646;110943.44528277307;114342;116250;116897.41551292189;118760;9854;9917.504124793732;12268.693282668004;12454;12611.243325400505;15728.05631662104;15877.841221587867;15892.364305427885;15918.522444326689;15955.441559545361;9071.727848164566;9236.907630923604;9325.953370233105;9390.765230869283;9398.906010939803;9058.547072646474;9319.487428191514;9345.485998270024;9470.810583788285;9478.810423791485;4075.449814275094;4753.358296629911;4860.295257187688;4896.559309662076;4897.608191344684;2048.621005114059;2073.450535608065;2077.491014701408;2085.843561732876;2142.0039681548155;942.6417961174847;944.1211988143544;956.1585804492141;1009.5557954500143;1069.5989004123453;20647;20932.16271349129;20948.37154885356;21202.78797212039;21255.78744212569;970.2529052629436;999.7453196238674;1014.8071866345371;1036.1503567074926;1070.7537266428765;10832.566697332019;11209.248278166477;11219.66341009771;11222;11243.381614011145;636950;653314;655667;664885.6755716191;665514;95781.52109239549;124449;127666;129467;129688;998.4009594243541;1029.8352263637782;1041.8437234414898;1043.6503771236569;1051.7265510967204;18227.179776909805;18864.811351886856;19021.42935711931;19135.52161195981;19363.4190974271;54639.72680136574;55086.34740957778;55562.44437555573;58273;58565;81078.59460702895;82288;82720;84405.57797210975;85041;5948.940510594839;7793.961030194812;7896.605169741605;8178.713745018896;8487.745367638981;9153.679621213225;11836.940815295868;12489.875101248872;12759.234445932902;12831.03767217481;8850.73447796567;9950.651727189514;11118.49966751482;11180.888191117985;11260.38067906273;10214.029667181605;10217.897821021694;10879.45602719845;10912.181586380895;11053;9113.179813816523;9622.951885240529;10548;11124.332540047299;11486.82769758471;6934.965325173342;8041.195880411912;8079.474834135763;8437.578121094044;8653.39426240157;10156.847647285149;11390.715232119263;11515.193936424705;11533.135014873751;11566.768664626828 10:15:26 INFO - PROCESS | 1915 | |3;mootools.html;1634.8446897544734;1655.7681924530564;1980.7524059492564;2058.60886431578;2099.9790002099976;8385.371097167706;9054.4114632549;9243.907560924388;9386.342955993083;9413.388129771569;9010.54947252638;9139.588718507666;9148.130927561888;9205.171534561889;9303.488308143067;2558.9232323030337;2564.205096420106;2573.7554932281496;2583.935401614956;2592.8703564821776;2511.1336588876898;2558.3859873630286;2564.756348146923;2576.768090871821;2577.0464927976673;2112.2501511963264;2523.2808649534886;2594.44219492809;2597.0910181436534;2599.54507961107;2549.8725063746924;2563.192594332779;2575.7681808637217;2575.948481030369;2603.557395242813;2182.6725991101302;2555.0546297869723;2566.0120853471362;2577.278362058608;2601.7268186840297;9111.362204645677;9303.674371396968;9336.066393360677;9373.10955459237;9402.247820174438;5323.22813192085;5419.647722898038;5608.523275521586;5616.66300022;5635.915461268084;3514.1390359361862;3529.170644898447;3537.9292414151832;3766.679832214265;3768.321702093622;1060.173065009294;1074.3392813419691;1077.439731339707;1079.6868908016745;1080.9136817498363;12286.447109880077;12287.13990020708;12565.811512827315;12585.811212831815;12640.620781376481;4981.9252711209365;5014.07239660664;5110.7955681773055;5135.435102138762;5198.948010519846;10629.84055239172;10646;10681.198910081775;10686.51910664022;10692.62575809843;5557.749901254453;5572.275604171513;5594.468525490072;5596.076647353141;5596.804111856147;4662.883427914261;5043;5128.538431541179;5154.819581314636;5204.1673332266655;4267.423897773823;4293;4315.697901146889;4334.9349759752995;4399.494058183286;1415.9575212743634;1438.6403399150213;1448.8840892728556;1450.9419623214953;1452.556970124101;873.3537182484915;939.8073394954018;975.3513913247752;977.3207620703542;997.9501024948723;6006.519478441714;6094.421030002142;6148.569600128038;6195.349488303754;6208.2239720034995;432.7317063420699;439.81967393368564;474.4780741184669;486.84907678619743;492.778249787597;3903.6681882039775;4019.9196016079513;4035.1122752994293;4038.63652271297;4120.670346372283;10840.349579025284;11086.334819910831;11407.942960285145;11525.135614828921;11536.25014374063;10682.78634427307;11189.72025699364;11237.719057023476;11422.828657570142;11445.198836081561;7280.8350663893525;8276.462029968034;8543.530105844115;8791.384603077724;8800.295976321879;5838.241028666416;5840;5903.498202652822;6055.878882422415;6071.846349193639;9862.556184971838;9961.800763984824;10039.749006274902;10053.648122315684;10057.29598928068;9965.651202207888;10230.539625716712;10312.639057632947;10343.172546196436;10346.948265258625;7107.111611048619;7598.658060387185;7843.882341764992;7866.331361834308;7981.122076571456;8534.487930724177;8624.827503449771;8642.049374568667;8679.218870301704;8708.56457177126;7209.35115839577;7632.923670763444;7774.144844067261;7852.842943140991;7881.487703299267;7545.886811697938;7592.240775922363;7598.20218877032;7608.771736847903;7647.388208943383;7044.929550704633;7045.506814523139;7085.362317391459;7165.498415110892;7197.064381630355;4987.60099192071;5002.474740152232;5119.897602048012;5165.535101840853;5207.6875387476875;5100.744962751922;5106.489351065013;5106.897862042812;5192.792288308426;5201.141811601195 10:15:26 INFO - PROCESS | 1915 | |4;prototype.html;339.15211970074813;340.4263815470932;342.0422816114879;347.7009771596427;349.32754447688194;7022.824429389268;7135.3934915532245;7146.213916469184;7188.029616001845;7200.135983681959;5657.9434205657935;6060.757569697218;6868.626274745046;7186.35322820946;7196.208417074117;5297.284866543016;5303.469653034705;5312.57499400049;5318.946810531884;5320.574354051667;4652.860049287912;4974.676646017997;5309.787608495655;5325.893482130355;5335.493128152839;5244.292020577212;5248.21276808478;5253.133233016548;5261.605379596526;5277.736113194344;5073.010762901217;5241.9213711794355;5261.657992230512;5264.184051472028;5272.367315922113;4498.650404878524;4513.52607976161;4525.954740452619;4531.56950089741;4533.637309015271;5495.258140151109;6839.931600683979;6879.552829066095;6880.311968803129;6927.7228910843505;4953.876153096166;6132.6933653317155;6152.87694246117;6163.383661633846;6183.628982261079;7646.694132234704;7649.847003059907;7659.195784442609;7669.194734552903;7690.115636701795;4857.004314115633;5038.496150384969;5040.387076135638;5041.697498150123;5044.621653376012;7003.82490437738;7939.523628582304;7963.761087167394;7984.520928744294;8008.959955200186;5112.105381558213;5127.9230811537855;5154.175331946945;5164.948350516522;5177.042247184208;5280.524752772269;5282.207668849626;5294.629375943647;5298.91372268684;5301.1783173608155;4696.929546056812;5288.206768984606;5376.6827127354045;5756.769729210785;5889.852753681106;7604.505707129019;7608.80977975555;7611.2769286917655;7622.237776222444;7637.809054773564;1792.211426972128;1834.3488061738158;1835.1833434121954;1839.9632007360046;1843.8156184381455;1088.646189988257;1151.0561339701364;1152.0840931459509;1168.1647621950176;1187.6021532786613;7109.608971506616;7121.679524421414;7130.893036604455;7160.785176444715;7170.032045673872;7905.169957154531;7933.880991785128;7955;7963.681452741941;7964.880526791987;6216.533759968021;6712.194536655535;7110.360067593838;7145.285471452813;7184.461165412615;3995.4006898965386;4154.356074808409;4177.97911010443;4191.601797829201;4210.873673789792;8245.546494942715;8287;8287.62705678246;8307.543085130375;8316.958415207886;8168.978877640295;8186.386021048445;8189.198376357192;8196.467229630054;8206.958965205136;8244.010718713793;8276.379271554657;8290.917090829134;8294.626741796514;8295.460795048302;7668.80827979305;7868.213178682086;7904.565248911135;7927;7935.841283174189;7677.846443071219;7697.961510192413;7716.691332346643;7718.228177182462;7732.110807257238;8226.424150309422;8251.669933202846;8268.297194738514;8281.964754405699;8292.751217463485;5190.195519694454;5199.064168449715;5199.064168449715;5213.739313034409;5216.869578260422;6761.290064543152;7714.537127772127;7864.72473463426;8080.878786818085;8086.3126634234395;7682.423818213544;7714.305712485679;7716.729914453192;7727.265909738566;7749.961250193713;7728.613569321624;7743.148253692202;7748.65131069092;7767.495112817649;7774.650140743794;7292.744753933587;7565.962170189114;7584.051993500812;7605.353544948742;7619.314261716472;7683.193264707125;7747.3802095833325;7751.612419379121;7780.610969451618;7787.961060194662;6085.939140608537;7238.8914166288605;7443.404527637667;7592.126905405949;7602.885956710543 10:15:26 INFO - PROCESS | 1915 | |5;yui.html;660.8215781738928;729.6060127531132;764.7878113190593;775.8409526047161;781.1173374087281;15202.923985380072;15250.084994900313;15260.46588369406;15272.236388180589;15576.45482408116;14356.64108397288;14464.5660630181;14479.855201447983;14513.782293265584;14540.781888271684;785.2775446589152;939.0468674295596;946.8248374050822;980.6224603527658;984.3503287830033;943.1558754914382;943.9528023598792;964.7009427077628;974.5468357213894;978.3102912446743;1019.7042857571296;1021.2289721260438;1025.2361990317224;1026.6406757634843;1030.587764894041;898.5013317608724;954.7851733359938;978.349397650565;980.36276420327;988.4662282367512;757.7650928212272;762.5843915066303;762.5882023707192;763.8510490454364;765.7013764631798;14729.852701472955;14774.039687420283;15125;15285.541433757006;15304.464343748026;13137.86862131376;13204.60386188416;13306.13510121839;13344.199348039148;13424.328783560783;10071.647492337806;10078.647247346382;10381.688549343457;10412.68761937143;10427.426491542961;3585.713142948558;3610.458431235336;3709.7403181777286;3746.1009357754215;3868.168343806095;77683.6115819423;78902.6054869722;79057.99999999942;79556.20443795546;80950.59524702397;9659.32384733076;9822.557984890696;9895.554700038516;10024.699259022242;10045.748856278506;17224.397146099825;21585.892070539547;21769.455763606038;21912.12351505922;21986.340409787732;7777.455578109591;7804.414668899855;7820.296173344426;7923.603819808986;8161.020677518736;4155.58444155582;4166.812493437803;4168.562300958356;4169.60388763067;4278.914421711548;3660.432632941898;3665.0104471792465;3726.8136593170234;3750.5686846012513;3756.4740936268945;634.213575166799;641.5060403489288;653.5261935097017;655.9606423614598;669.7957123077508;578.9131630255496;586.1325238646826;587.4507335641189;594.1622312539299;609.6220343387129;8354.415190936697;8362.665493380196;8366.163383661707;8405.70580029696;8543.444676096035;507.68269831355406;594.337313895004;595.6604735300923;605.4762630324825;612.0788213738296;3529.5235143255845;3570.892873213808;3762.6425489578446;3849.8075096245075;4001.099752555652;87160.69258961121;87519.12480875109;87906.56046719853;87944.6808297876;89469.10530894608;70954;71480;71951.28048719445;72634.63682681553;73177;69369;69953.60092798116;71632.64183679153;71952.2804771956;72754.9086763699;6888.207856096613;6889.483288753263;6921.861562768817;7159.677814498465;7214;50320;50408.49591504038;50856;51022.744886276814;51369.486305136474;65189.34810652022;68651.65674171597;70352.29647703457;70882.29117708757;71436.64281678558;9742.634237717042;9796.853047204155;9914.752131196778;10053.547590358305;10134.189264858942;10650.201234907257;10965.451727413438;11153.442327883411;11155.776884462428;11167.553297868319;10326.277160598986;10394.376337419779;10432.374057556572;10537.42044187577;10669.199810014436;9883;10152.949235254073;10225.43760093202;10359.792804144026;11120.88879111231;10057.145142662956;10062.547185376821;10191.796164076824;10203.489825508546;10816;7898.921010789819;8738.169873861973;8769.517676527852;9063;9083.636654533744;7407.407407407287;7474.850502990019;8859.202671759573;9054.643010302267;9104.635814567344 10:15:26 INFO - PROCESS | 1915 | __end_tp_report 10:15:26 INFO - PROCESS | 1915 | __start_cc_report 10:15:26 INFO - PROCESS | 1915 | _x_x_mozilla_cycle_collect,394 10:15:26 INFO - PROCESS | 1915 | __end_cc_report 10:15:26 INFO - PROCESS | 1915 | __startTimestamp1468602926915__endTimestamp 10:15:26 INFO - PROCESS | 1915 | 10:15:26 INFO - PROCESS | 1915 | ------- Summary: start ------- 10:15:26 INFO - PROCESS | 1915 | Number of tests: 6 10:15:26 INFO - PROCESS | 1915 | 10:15:26 INFO - PROCESS | 1915 | [#0] dojo.html Cycles:165 Average:6025.64 Median:6230.60 stddev:644.51 (10.3%) stddev-sans-first:600.95 10:15:26 INFO - PROCESS | 1915 | Values: 2991.8 3668.8 3713.5 3744.3 3777.2 5925.7 6036.6 6045.9 6136.9 6176.6 6162.2 6165.3 6180.2 6196.9 6208.1 5460.9 5523.2 5569.8 5614.7 5655.3 5480.8 5566.8 5606.0 5631.5 5683.1 5562.2 5567.3 5575.3 5581.7 5581.7 5531.3 5539.3 5556.9 5599.2 5603.8 5400.8 5551.0 5555.4 5578.2 5580.8 6117.8 6120.4 6123.6 6148.6 6172.5 6261.2 6354.7 6396.8 6401.0 6411.4 6509.0 6530.2 6537.5 6538.4 6552.7 6203.5 6220.6 6224.0 6234.2 6235.4 6428.1 6543.9 6593.6 6639.7 6656.2 6310.7 6333.0 6334.0 6349.4 6354.2 6526.2 6533.8 6550.0 6555.0 6567.9 6182.0 6271.2 6281.7 6368.5 6373.6 6314.2 6319.5 6353.4 6368.6 6376.3 5076.1 5095.9 5137.9 5185.1 5216.0 4641.3 4648.0 4666.5 4685.0 4722.8 6182.2 6209.6 6223.1 6232.3 6249.8 6352.3 6411.5 6506.7 6520.4 6521.5 5468.2 6015.2 6127.7 6169.8 6178.0 5680.0 5719.3 5719.4 5739.0 5739.6 6651.2 6692.5 6787.6 6802.5 6808.4 6401.3 6466.9 6472.9 6490.4 6512.7 6665.8 6728.0 6731.2 6735.4 6744.2 6292.4 6388.6 6420.7 6426.5 6445.7 6514.4 6521.8 6559.3 6561.5 6582.4 6349.3 6355.4 6356.6 6362.4 6369.9 6199.9 6290.0 6298.2 6301.1 6340.6 5429.2 5486.2 5486.4 6203.3 6220.8 5516.8 6275.5 6304.7 6320.9 6325.9 6091.6 6160.2 6207.2 6230.6 6237.3 10:15:26 INFO - PROCESS | 1915 | 10:15:26 INFO - PROCESS | 1915 | [#1] ext.html Cycles:180 Average:67599.91 Median:220418.20 stddev:146383.65 (66.4%) stddev-sans-first:146707.67 10:15:26 INFO - PROCESS | 1915 | Values: 525.2 545.7 558.6 593.0 603.0 30811.2 39127.6 39159.2 39220.8 39222.0 40024.2 40710.0 41189.8 41279.4 41294.8 9640.7 9756.3 9826.1 9911.4 9939.7 9797.0 9867.9 9872.3 9924.1 9988.1 9872.1 9940.7 9965.2 9988.5 10055.8 9880.7 9949.3 9983.5 10020.6 10040.1 9815.9 9827.1 9919.7 9925.2 9939.5 32686.0 35302.9 39384.4 39561.6 40541.8 3142.8 3355.5 3376.0 3409.7 3414.2 28107.7 28199.7 28884.6 29350.3 29501.6 2646.1 2760.8 2761.2 2788.8 2966.4 353261.0 400685.0 408326.0 412594.0 415083.8 37164.3 37895.6 63293.7 64094.4 64326.7 35234.6 35798.3 35876.0 35893.8 36087.0 15903.0 15903.4 16091.9 16105.4 16135.6 13104.5 13323.3 13390.9 13432.3 13509.7 8358.8 8362.3 8498.7 8499.3 8521.8 3543.3 3564.9 3575.6 3586.7 3618.1 978.3 1048.9 1063.8 1092.6 1129.9 29195.6 30521.8 30592.0 30815.3 30913.0 1078.7 1149.4 1167.3 1200.0 1233.4 13609.5 15304.4 15457.4 15601.1 16109.4 623528.0 626681.0 628340.0 629479.0 639555.0 61907.7 62407.7 62576.7 62582.0 62601.1 1503.1 1526.8 1547.3 1548.7 1622.1 29256.3 29817.6 30936.7 31407.4 34492.5 63960.4 64496.7 64898.4 65006.7 65126.7 574682.0 586862.0 587552.0 589571.0 593056.0 41985.6 45611.8 45622.3 47468.8 48838.0 50013.5 50330.2 51476.5 51699.5 52241.0 40590.6 41259.0 41329.6 41514.6 42140.7 40151.4 40730.8 40892.4 41279.0 42010.6 41304.0 41549.0 42045.6 42972.8 43016.6 31932.2 33813.7 33956.8 34221.0 34479.7 22863.0 23012.2 26568.1 26903.0 27072.9 10:15:26 INFO - PROCESS | 1915 | 10:15:26 INFO - PROCESS | 1915 | [#2] jquery.html Cycles:180 Average:36523.83 Median:2297.33 stddev:108777.52 (4735.0%) stddev-sans-first:109048.85 10:15:26 INFO - PROCESS | 1915 | Values: 396.3 415.8 416.2 420.4 427.4 15529.5 16865.8 20480.5 21084.5 21190.0 20087.8 20634.9 20859.2 21217.9 21233.8 5744.3 5861.3 6044.5 6078.9 6169.9 5808.1 5965.2 6003.5 6012.3 6013.9 5252.7 6271.2 6328.5 6336.0 6370.7 5823.7 6210.6 6318.3 6321.2 6343.6 5996.9 6006.0 6007.7 6008.7 6009.5 14122.9 17563.6 21085.6 26512.6 26896.5 2149.9 2252.7 2342.0 2346.8 2577.8 10154.2 10285.9 10293.3 10425.7 10580.0 1594.4 1609.3 1813.5 1824.6 1847.4 110943.4 114342.0 116250.0 116897.4 118760.0 9854.0 9917.5 12268.7 12454.0 12611.2 15728.1 15877.8 15892.4 15918.5 15955.4 9071.7 9236.9 9326.0 9390.8 9398.9 9058.5 9319.5 9345.5 9470.8 9478.8 4075.4 4753.4 4860.3 4896.6 4897.6 2048.6 2073.5 2077.5 2085.8 2142.0 942.6 944.1 956.2 1009.6 1069.6 20647.0 20932.2 20948.4 21202.8 21255.8 970.3 999.7 1014.8 1036.2 1070.8 10832.6 11209.2 11219.7 11222.0 11243.4 636950.0 653314.0 655667.0 664885.7 665514.0 95781.5 124449.0 127666.0 129467.0 129688.0 998.4 1029.8 1041.8 1043.7 1051.7 18227.2 18864.8 19021.4 19135.5 19363.4 54639.7 55086.3 55562.4 58273.0 58565.0 81078.6 82288.0 82720.0 84405.6 85041.0 5948.9 7794.0 7896.6 8178.7 8487.7 9153.7 11836.9 12489.9 12759.2 12831.0 8850.7 9950.7 11118.5 11180.9 11260.4 10214.0 10217.9 10879.5 10912.2 11053.0 9113.2 9623.0 10548.0 11124.3 11486.8 6935.0 8041.2 8079.5 8437.6 8653.4 10156.8 11390.7 11515.2 11533.1 11566.8 10:15:26 INFO - PROCESS | 1915 | 10:15:26 INFO - PROCESS | 1915 | [#3] mootools.html Cycles:180 Average:5957.34 Median:5008.27 stddev:3342.34 (66.7%) stddev-sans-first:3335.93 10:15:26 INFO - PROCESS | 1915 | Values: 1634.8 1655.8 1980.8 2058.6 2100.0 8385.4 9054.4 9243.9 9386.3 9413.4 9010.5 9139.6 9148.1 9205.2 9303.5 2558.9 2564.2 2573.8 2583.9 2592.9 2511.1 2558.4 2564.8 2576.8 2577.0 2112.3 2523.3 2594.4 2597.1 2599.5 2549.9 2563.2 2575.8 2575.9 2603.6 2182.7 2555.1 2566.0 2577.3 2601.7 9111.4 9303.7 9336.1 9373.1 9402.2 5323.2 5419.6 5608.5 5616.7 5635.9 3514.1 3529.2 3537.9 3766.7 3768.3 1060.2 1074.3 1077.4 1079.7 1080.9 12286.4 12287.1 12565.8 12585.8 12640.6 4981.9 5014.1 5110.8 5135.4 5198.9 10629.8 10646.0 10681.2 10686.5 10692.6 5557.7 5572.3 5594.5 5596.1 5596.8 4662.9 5043.0 5128.5 5154.8 5204.2 4267.4 4293.0 4315.7 4334.9 4399.5 1416.0 1438.6 1448.9 1450.9 1452.6 873.4 939.8 975.4 977.3 998.0 6006.5 6094.4 6148.6 6195.3 6208.2 432.7 439.8 474.5 486.8 492.8 3903.7 4019.9 4035.1 4038.6 4120.7 10840.3 11086.3 11407.9 11525.1 11536.3 10682.8 11189.7 11237.7 11422.8 11445.2 7280.8 8276.5 8543.5 8791.4 8800.3 5838.2 5840.0 5903.5 6055.9 6071.8 9862.6 9961.8 10039.7 10053.6 10057.3 9965.7 10230.5 10312.6 10343.2 10346.9 7107.1 7598.7 7843.9 7866.3 7981.1 8534.5 8624.8 8642.0 8679.2 8708.6 7209.4 7632.9 7774.1 7852.8 7881.5 7545.9 7592.2 7598.2 7608.8 7647.4 7044.9 7045.5 7085.4 7165.5 7197.1 4987.6 5002.5 5119.9 5165.5 5207.7 5100.7 5106.5 5106.9 5192.8 5201.1 10:15:26 INFO - PROCESS | 1915 | 10:15:26 INFO - PROCESS | 1915 | [#4] prototype.html Cycles:180 Average:6235.00 Median:7116.02 stddev:1999.77 (28.1%) stddev-sans-first:1955.80 10:15:26 INFO - PROCESS | 1915 | Values: 339.2 340.4 342.0 347.7 349.3 7022.8 7135.4 7146.2 7188.0 7200.1 5657.9 6060.8 6868.6 7186.4 7196.2 5297.3 5303.5 5312.6 5318.9 5320.6 4652.9 4974.7 5309.8 5325.9 5335.5 5244.3 5248.2 5253.1 5261.6 5277.7 5073.0 5241.9 5261.7 5264.2 5272.4 4498.7 4513.5 4526.0 4531.6 4533.6 5495.3 6839.9 6879.6 6880.3 6927.7 4953.9 6132.7 6152.9 6163.4 6183.6 7646.7 7649.8 7659.2 7669.2 7690.1 4857.0 5038.5 5040.4 5041.7 5044.6 7003.8 7939.5 7963.8 7984.5 8009.0 5112.1 5127.9 5154.2 5164.9 5177.0 5280.5 5282.2 5294.6 5298.9 5301.2 4696.9 5288.2 5376.7 5756.8 5889.9 7604.5 7608.8 7611.3 7622.2 7637.8 1792.2 1834.3 1835.2 1840.0 1843.8 1088.6 1151.1 1152.1 1168.2 1187.6 7109.6 7121.7 7130.9 7160.8 7170.0 7905.2 7933.9 7955.0 7963.7 7964.9 6216.5 6712.2 7110.4 7145.3 7184.5 3995.4 4154.4 4178.0 4191.6 4210.9 8245.5 8287.0 8287.6 8307.5 8317.0 8169.0 8186.4 8189.2 8196.5 8207.0 8244.0 8276.4 8290.9 8294.6 8295.5 7668.8 7868.2 7904.6 7927.0 7935.8 7677.8 7698.0 7716.7 7718.2 7732.1 8226.4 8251.7 8268.3 8282.0 8292.8 5190.2 5199.1 5199.1 5213.7 5216.9 6761.3 7714.5 7864.7 8080.9 8086.3 7682.4 7714.3 7716.7 7727.3 7750.0 7728.6 7743.1 7748.7 7767.5 7774.7 7292.7 7566.0 7584.1 7605.4 7619.3 7683.2 7747.4 7751.6 7780.6 7788.0 6085.9 7238.9 7443.4 7592.1 7602.9 10:15:26 INFO - PROCESS | 1915 | 10:15:26 INFO - PROCESS | 1915 | [#5] yui.html Cycles:180 Average:17907.30 Median:607.55 stddev:25095.29 (4130.6%) stddev-sans-first:25132.27 10:15:26 INFO - PROCESS | 1915 | Values: 660.8 729.6 764.8 775.8 781.1 15202.9 15250.1 15260.5 15272.2 15576.5 14356.6 14464.6 14479.9 14513.8 14540.8 785.3 939.0 946.8 980.6 984.4 943.2 944.0 964.7 974.5 978.3 1019.7 1021.2 1025.2 1026.6 1030.6 898.5 954.8 978.3 980.4 988.5 757.8 762.6 762.6 763.9 765.7 14729.9 14774.0 15125.0 15285.5 15304.5 13137.9 13204.6 13306.1 13344.2 13424.3 10071.6 10078.6 10381.7 10412.7 10427.4 3585.7 3610.5 3709.7 3746.1 3868.2 77683.6 78902.6 79058.0 79556.2 80950.6 9659.3 9822.6 9895.6 10024.7 10045.7 17224.4 21585.9 21769.5 21912.1 21986.3 7777.5 7804.4 7820.3 7923.6 8161.0 4155.6 4166.8 4168.6 4169.6 4278.9 3660.4 3665.0 3726.8 3750.6 3756.5 634.2 641.5 653.5 656.0 669.8 578.9 586.1 587.5 594.2 609.6 8354.4 8362.7 8366.2 8405.7 8543.4 507.7 594.3 595.7 605.5 612.1 3529.5 3570.9 3762.6 3849.8 4001.1 87160.7 87519.1 87906.6 87944.7 89469.1 70954.0 71480.0 71951.3 72634.6 73177.0 69369.0 69953.6 71632.6 71952.3 72754.9 6888.2 6889.5 6921.9 7159.7 7214.0 50320.0 50408.5 50856.0 51022.7 51369.5 65189.3 68651.7 70352.3 70882.3 71436.6 9742.6 9796.9 9914.8 10053.5 10134.2 10650.2 10965.5 11153.4 11155.8 11167.6 10326.3 10394.4 10432.4 10537.4 10669.2 9883.0 10152.9 10225.4 10359.8 11120.9 10057.1 10062.5 10191.8 10203.5 10816.0 7898.9 8738.2 8769.5 9063.0 9083.6 7407.4 7474.9 8859.2 9054.6 9104.6 10:15:26 INFO - PROCESS | 1915 | -------- Summary: end -------- 10:15:26 INFO - PROCESS | 1915 | 10:15:27 INFO - TEST-INFO | 1915: exit 0 10:15:27 INFO - TEST-OK | dromaeo_css | took 1110300ms 10:15:27 INFO - TEST-START | kraken 10:15:27 INFO - operating with platform_type : mac_ 10:15:27 INFO - Initialising browser for kraken test... 10:15:27 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpuCIXoZ/profile http://localhost:49294/getInfo.html) 10:15:30 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 10:15:30 INFO - PROCESS | 1922 | colorDepth:24 10:15:30 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 10:15:30 INFO - PROCESS | 1922 | __metrics 10:15:30 ERROR - PROCESS | 1922 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 10:15:30 INFO - TEST-INFO | 1922: exit 0 10:15:30 INFO - Browser initialized. 10:15:30 INFO - Running cycle 1/1 for kraken test... 10:15:30 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpuCIXoZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:15:42 INFO - PROCESS | 1926 | RSS: Main: 171085824 10:15:42 INFO - PROCESS | 1926 | 10:15:45 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49294/tests/kraken/driver.html?testName=audio-beat-detection) 10:15:46 INFO - PROCESS | 1926 | RSS: Main: 183603200 10:15:46 INFO - PROCESS | 1926 | 10:15:48 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49294/tests/kraken/driver.html?testName=audio-dft) 10:15:49 INFO - PROCESS | 1926 | RSS: Main: 192655360 10:15:49 INFO - PROCESS | 1926 | 10:15:52 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49294/tests/kraken/driver.html?testName=audio-fft) 10:15:53 INFO - PROCESS | 1926 | RSS: Main: 196341760 10:15:53 INFO - PROCESS | 1926 | 10:15:55 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49294/tests/kraken/driver.html?testName=audio-oscillator) 10:15:55 INFO - PROCESS | 1926 | RSS: Main: 197636096 10:15:55 INFO - PROCESS | 1926 | 10:15:57 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49294/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:15:58 INFO - PROCESS | 1926 | RSS: Main: 197988352 10:15:58 INFO - PROCESS | 1926 | 10:16:01 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49294/tests/kraken/driver.html?testName=imaging-darkroom) 10:16:02 INFO - PROCESS | 1926 | RSS: Main: 198475776 10:16:02 INFO - PROCESS | 1926 | 10:16:04 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49294/tests/kraken/driver.html?testName=imaging-desaturate) 10:16:05 INFO - PROCESS | 1926 | RSS: Main: 198217728 10:16:05 INFO - PROCESS | 1926 | 10:16:08 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49294/tests/kraken/driver.html?testName=json-parse-financial) 10:16:09 INFO - PROCESS | 1926 | RSS: Main: 198447104 10:16:09 INFO - PROCESS | 1926 | 10:16:10 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49294/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:16:11 INFO - PROCESS | 1926 | RSS: Main: 197660672 10:16:11 INFO - PROCESS | 1926 | 10:16:13 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49294/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:16:13 INFO - PROCESS | 1926 | RSS: Main: 197672960 10:16:13 INFO - PROCESS | 1926 | 10:16:15 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49294/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:16:16 INFO - PROCESS | 1926 | RSS: Main: 197951488 10:16:16 INFO - PROCESS | 1926 | 10:16:19 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49294/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:16:19 INFO - PROCESS | 1926 | RSS: Main: 198189056 10:16:19 INFO - PROCESS | 1926 | 10:16:22 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49294/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:16:23 INFO - PROCESS | 1926 | RSS: Main: 198156288 10:16:23 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49294/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49294/tests/kraken/driver.html?testName=ai-astar) 10:16:25 INFO - PROCESS | 1926 | RSS: Main: 200171520 10:16:25 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | __start_tp_report 10:16:25 INFO - PROCESS | 1926 | _x_x_mozilla_page_load 10:16:25 INFO - PROCESS | 1926 | _x_x_mozilla_page_load_details 10:16:25 INFO - PROCESS | 1926 | |i|pagename|runs| 10:16:25 INFO - PROCESS | 1926 | |0;ai-astar;115;122;113;116;116;131;118;115;115;142 10:16:25 INFO - PROCESS | 1926 | |1;audio-beat-detection;178;118;147;153;123;127;153;177;157;115 10:16:25 INFO - PROCESS | 1926 | |2;audio-dft;198;197;201;195;195;195;195;193;197;222 10:16:25 INFO - PROCESS | 1926 | |3;audio-fft;65;70;112;179;84;55;56;58;124;69 10:16:25 INFO - PROCESS | 1926 | |4;audio-oscillator;76;81;124;77;75;83;118;80;78;119 10:16:25 INFO - PROCESS | 1926 | |5;imaging-gaussian-blur;90;88;101;81;84;130;134;122;91;88 10:16:25 INFO - PROCESS | 1926 | |6;imaging-darkroom;96;97;96;119;119;96;95;96;96;96 10:16:25 INFO - PROCESS | 1926 | |7;imaging-desaturate;89;93;121;149;96;89;90;91;88;89 10:16:25 INFO - PROCESS | 1926 | |8;json-parse-financial;57;64;66;64;65;65;64;79;62;60 10:16:25 INFO - PROCESS | 1926 | |9;json-stringify-tinderbox;62;67;71;75;72;73;66;67;66;68 10:16:25 INFO - PROCESS | 1926 | |10;stanford-crypto-aes;80;81;103;103;107;101;80;79;86;84 10:16:25 INFO - PROCESS | 1926 | |11;stanford-crypto-ccm;125;127;128;127;127;129;150;139;175;129 10:16:25 INFO - PROCESS | 1926 | |12;stanford-crypto-pbkdf2;187;151;221;214;168;166;221;210;183;165 10:16:25 INFO - PROCESS | 1926 | |13;stanford-crypto-sha256-iterative;102;101;93;68;71;68;67;65;68;75 10:16:25 INFO - PROCESS | 1926 | __end_tp_report 10:16:25 INFO - PROCESS | 1926 | __start_cc_report 10:16:25 INFO - PROCESS | 1926 | _x_x_mozilla_cycle_collect,1046 10:16:25 INFO - PROCESS | 1926 | __end_cc_report 10:16:25 INFO - PROCESS | 1926 | __startTimestamp1468602985604__endTimestamp 10:16:25 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | ------- Summary: start ------- 10:16:25 INFO - PROCESS | 1926 | Number of tests: 14 10:16:25 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | [#0] ai-astar Cycles:10 Average:120.30 Median:117.00 stddev:9.24 (7.9%) stddev-sans-first:9.60 10:16:25 INFO - PROCESS | 1926 | Values: 115.0 122.0 113.0 116.0 116.0 131.0 118.0 115.0 115.0 142.0 10:16:25 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | [#1] audio-beat-detection Cycles:10 Average:144.80 Median:153.00 stddev:23.16 (15.1%) stddev-sans-first:21.22 10:16:25 INFO - PROCESS | 1926 | Values: 178.0 118.0 147.0 153.0 123.0 127.0 153.0 177.0 157.0 115.0 10:16:25 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | [#2] audio-dft Cycles:10 Average:198.80 Median:197.00 stddev:8.44 (4.3%) stddev-sans-first:8.95 10:16:25 INFO - PROCESS | 1926 | Values: 198.0 197.0 201.0 195.0 195.0 195.0 195.0 193.0 197.0 222.0 10:16:25 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | [#3] audio-fft Cycles:10 Average:87.20 Median:61.50 stddev:39.99 (65.0%) stddev-sans-first:41.60 10:16:25 INFO - PROCESS | 1926 | Values: 65.0 70.0 112.0 179.0 84.0 55.0 56.0 58.0 124.0 69.0 10:16:25 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:77.50 stddev:20.37 (26.3%) stddev-sans-first:20.86 10:16:25 INFO - PROCESS | 1926 | Values: 76.0 81.0 124.0 77.0 75.0 83.0 118.0 80.0 78.0 119.0 10:16:25 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | [#5] imaging-gaussian-blur Cycles:10 Average:100.90 Median:86.00 stddev:20.05 (23.3%) stddev-sans-first:20.88 10:16:25 INFO - PROCESS | 1926 | Values: 90.0 88.0 101.0 81.0 84.0 130.0 134.0 122.0 91.0 88.0 10:16:25 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | [#6] imaging-darkroom Cycles:10 Average:100.60 Median:96.00 stddev:9.71 (10.1%) stddev-sans-first:10.15 10:16:25 INFO - PROCESS | 1926 | Values: 96.0 97.0 96.0 119.0 119.0 96.0 95.0 96.0 96.0 96.0 10:16:25 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | [#7] imaging-desaturate Cycles:10 Average:99.50 Median:89.50 stddev:19.98 (22.3%) stddev-sans-first:20.83 10:16:25 INFO - PROCESS | 1926 | Values: 89.0 93.0 121.0 149.0 96.0 89.0 90.0 91.0 88.0 89.0 10:16:25 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:64.50 stddev:5.74 (8.9%) stddev-sans-first:5.39 10:16:25 INFO - PROCESS | 1926 | Values: 57.0 64.0 66.0 64.0 65.0 65.0 64.0 79.0 62.0 60.0 10:16:25 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.50 stddev:3.95 (5.7%) stddev-sans-first:3.36 10:16:25 INFO - PROCESS | 1926 | Values: 62.0 67.0 71.0 75.0 72.0 73.0 66.0 67.0 66.0 68.0 10:16:25 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | [#10] stanford-crypto-aes Cycles:10 Average:90.40 Median:80.00 stddev:11.55 (14.4%) stddev-sans-first:11.62 10:16:25 INFO - PROCESS | 1926 | Values: 80.0 81.0 103.0 103.0 107.0 101.0 80.0 79.0 86.0 84.0 10:16:25 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | [#11] stanford-crypto-ccm Cycles:10 Average:135.60 Median:129.00 stddev:15.81 (12.3%) stddev-sans-first:16.30 10:16:25 INFO - PROCESS | 1926 | Values: 125.0 127.0 128.0 127.0 127.0 129.0 150.0 139.0 175.0 129.0 10:16:25 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.60 Median:198.50 stddev:26.12 (13.2%) stddev-sans-first:27.70 10:16:25 INFO - PROCESS | 1926 | Values: 187.0 151.0 221.0 214.0 168.0 166.0 221.0 210.0 183.0 165.0 10:16:25 INFO - PROCESS | 1926 | 10:16:25 INFO - PROCESS | 1926 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.80 Median:68.00 stddev:14.82 (21.8%) stddev-sans-first:12.88 10:16:25 INFO - PROCESS | 1926 | Values: 102.0 101.0 93.0 68.0 71.0 68.0 67.0 65.0 68.0 75.0 10:16:25 INFO - PROCESS | 1926 | -------- Summary: end -------- 10:16:25 INFO - PROCESS | 1926 | 10:16:26 INFO - TEST-INFO | 1926: exit 0 10:16:26 INFO - TEST-OK | kraken | took 58828ms 10:16:26 INFO - SUITE-END | took 1169s 10:16:26 INFO - Completed test suite (00:19:29) 10:16:26 INFO - javascript benchmark 10:16:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2991.8055326403783, 3668.8349024293902, 3713.4801127842125, 3744.3073031489175, 3777.1690228149796, 5925.703714814258, 6036.577439579231, 6045.939540604598, 6136.877262454748, 6176.567640265178, 6162.168107305512, 6165.290991535975, 6180.227471566054, 6196.9465190917535, 6208.130861679358, 5460.918086228709, 5523.226748255227, 5569.777208911639, 5614.69119198446, 5655.26481557399, 5480.7533660985155, 5566.805161819337, 5606.018946684334, 5631.521320687746, 5683.062294721387, 5562.16567514874, 5567.3040869891265, 5575.275214222166, 5581.6930068846195, 5581.69300688464, 5531.3362396512675, 5539.279893613824, 5556.91664625031, 5599.216109744639, 5603.803866864681, 5400.78396864125, 5550.972245138789, 5555.444455554412, 5578.219049333096, 5580.832575022756, 6117.847053823645, 6120.418560236771, 6123.571350005502, 6148.569600127993, 6172.506199504029, 6261.154744109533, 6354.7458101675875, 6396.808095757088, 6401.039844023434, 6411.3909178627955, 6509.0, 6530.216374035099, 6537.509686773511, 6538.411542961157, 6552.705128269239, 6203.503719702413, 6220.626762394271, 6224.0, 6234.220722409699, 6235.376462353774, 6428.100065990765, 6543.901841472381, 6593.571417857824, 6639.668016599151, 6656.201255849329, 6310.684465776692, 6332.968335158294, 6334.018227174795, 6349.428551430394, 6354.205724284465, 6526.216853977553, 6533.8366540835905, 6550.0, 6555.0, 6567.868642627216, 6181.96909015461, 6271.216097987752, 6281.748730050838, 6368.490520758328, 6373.553851230369, 6314.17915670969, 6319.526035547352, 6353.364663533702, 6368.554201205871, 6376.33048529907, 5076.0863044652315, 5095.923561146586, 5137.948620513747, 5185.066687996122, 5215.973920130375, 4641.327007583949, 4648.0, 4666.510016448292, 4685.01614660925, 4722.787474563653, 6182.227221597301, 6209.627422354673, 6223.097650840693, 6232.3144454110015, 6249.78125765596, 6352.2694890087305, 6411.4870810336, 6506.674666266667, 6520.380563846427, 6521.47828173745, 5468.207109969112, 6015.248093988252, 6127.708789264043, 6169.784057557964, 6178.011518157074, 5679.971600142056, 5719.28508936383, 5719.370869204384, 5738.99567575681, 5739.598228123991, 6651.168603924412, 6692.498062645223, 6787.5588086774205, 6802.523823332418, 6808.387245147861, 6401.263854656774, 6466.870662586628, 6472.902906456501, 6490.383413575702, 6512.706928188338, 6665.800025999228, 6728.024436456784, 6731.225909020525, 6735.360140786617, 6744.224414192235, 6292.433680968735, 6388.55280130405, 6420.7431702731365, 6426.4537514311805, 6445.677716114082, 6514.446272066738, 6521.804345869631, 6559.344065593594, 6561.507886908405, 6582.407583317525, 6349.333320001333, 6355.428011478989, 6356.618602883842, 6362.427381535684, 6369.936300636934, 6199.876002480015, 6290.0, 6298.212723409574, 6301.149344838388, 6340.587861788969, 5429.212764149255, 5486.1770734388565, 5486.369067557282, 6203.348648391854, 6220.782272620437, 5516.779328826802, 6275.507658534188, 6304.747810087545, 6320.936790632035, 6325.8734825304155, 6091.634501929899, 6160.168377269011, 6207.16203312547, 6230.59501132425, 6237.345078766664], "value": 5985.978475786311, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [525.187756837183, 545.7135004122838, 558.5894367639785, 593.0215145010729, 602.9779523707315, 30811.22971925703, 39127.60872391275, 39159.216815663705, 39220.80389598048, 39222.01944951378, 40024.19951600966, 40710.0, 41189.794051029705, 41279.38080928781, 41294.7935260324, 9640.662576809813, 9756.317057805956, 9826.066523680274, 9911.355761875455, 9939.652112176076, 9797.0, 9867.901320986806, 9872.259580531452, 9924.05721456464, 9988.051135142186, 9872.062154095349, 9940.701778946606, 9965.202783777317, 9988.500574971258, 10055.798884022315, 9880.703578892644, 9949.253805964545, 9983.500824958723, 10020.649277275297, 10040.096391324743, 9815.920248772629, 9827.066428689262, 9919.652812151613, 9925.2059835213, 9939.453330066843, 32686.019419417218, 35302.94091177269, 39384.409233861516, 39561.604383956364, 40541.79729101365, 3142.8114313141286, 3355.547001154862, 3376.0, 3409.7272218222483, 3414.163529935182, 28107.718922810716, 28199.718002819915, 28884.566731499046, 29350.266243344086, 29501.557476637652, 2646.1135519600957, 2760.751532362077, 2761.240658818821, 2788.8047836651444, 2966.3770608172094, 353261.0, 400685.0, 408325.9583702092, 412594.0, 415083.77374339406, 37164.25671486609, 37895.62104378976, 63293.68353158205, 64094.35905640977, 64326.67836660787, 35234.64765352365, 35798.28403431917, 35876.0, 35893.82053089718, 36087.0, 15902.966307189996, 15903.363865445484, 16091.919540402223, 16105.355785768672, 16135.596610084842, 13104.475820967245, 13323.267220302976, 13390.866091338961, 13432.261225632488, 13509.662258443617, 8358.791030224293, 8362.331013518904, 8498.74503764888, 8499.277561407349, 8521.82956340882, 3543.291341731664, 3564.9483402396354, 3575.606683264839, 3586.6951309138494, 3618.113562177231, 978.3347323819871, 1048.9091345001113, 1063.7925604507202, 1092.6285063078592, 1129.8644861913888, 29195.56206656902, 30521.827695011507, 30592.0, 30815.305158216514, 30913.0, 1078.6840055132725, 1149.40231079838, 1167.3054532553117, 1199.9740222110145, 1233.4141282890735, 13609.455621775018, 15304.387824486896, 15457.38170473191, 15601.054769554541, 16109.436169734237, 623528.0, 626681.0, 628340.0, 629479.0, 639555.0, 61907.690461547405, 62407.68796156081, 62576.68711656413, 62582.0, 62601.06098408528, 1503.1356969742442, 1526.8167819861465, 1547.2727817925781, 1548.7212301785787, 1622.051100106451, 29256.26859328534, 29817.5527367094, 30936.69063309338, 31407.37185256328, 34492.482612761334, 63960.3603963973, 64496.677516612115, 64898.35101649112, 65006.674966624865, 65126.67436662786, 574682.0, 586862.0, 587552.0, 589571.0, 593056.0, 41985.58014419939, 45611.77194114008, 45622.31566526438, 47468.762656186496, 48838.02323953572, 50013.499865000886, 50330.2450463236, 51476.48523514717, 51699.483005169466, 52240.955180896926, 40590.59409405868, 41258.968525787095, 41329.58670413258, 41514.58485415228, 42140.73577792671, 40151.397729034674, 40730.79634601808, 40892.38661420141, 41279.0, 42010.579894200666, 41304.0, 41549.0, 42045.57954420539, 42972.78513607412, 43016.569834301255, 31932.201694957814, 33813.66186338105, 33956.830215848764, 34221.0, 34479.65520344764, 22863.0, 23012.19457318986, 26568.070117545794, 26903.0, 27072.864635676695], "value": 19428.97172559345, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.3440505962632, 415.79625983268187, 416.15312838373916, 420.42821762403105, 427.39736970871076, 15529.456469023586, 16865.83134168658, 20480.487987800312, 21084.472888177766, 21190.04644790984, 20087.799122008775, 20634.89682551589, 20859.165633374647, 21217.893910530463, 21233.787662123414, 5744.281964754406, 5861.296644402667, 6044.455998960093, 6078.908816367758, 6169.907451388232, 5808.083332500286, 5965.164876917235, 6003.459688628022, 6012.308584512772, 6013.939860601404, 5252.6848389096585, 6271.247450305947, 6328.462080723144, 6335.968320158393, 6370.713317900682, 5823.6505809651135, 6210.596311239755, 6318.336574659664, 6321.178246827929, 6343.619382837044, 5996.880062398771, 6006.0, 6007.729652165619, 6008.708127752554, 6009.489193418564, 14122.858771412257, 17563.56091097733, 21085.578288434146, 26512.60231096535, 26896.46207075867, 2149.9247526336503, 2252.68462415262, 2341.976580234193, 2346.8005219556358, 2577.7937764978756, 10154.238432117621, 10285.897141028569, 10293.279470437072, 10425.687229383131, 10580.0, 1594.4339759385366, 1609.3401705300887, 1813.519417354402, 1824.616830465604, 1847.4088291746646, 110943.44528277307, 114342.0, 116250.0, 116897.41551292189, 118760.0, 9854.0, 9917.504124793732, 12268.693282668004, 12454.0, 12611.243325400505, 15728.05631662104, 15877.841221587867, 15892.364305427885, 15918.522444326689, 15955.441559545361, 9071.727848164566, 9236.907630923604, 9325.953370233105, 9390.765230869283, 9398.906010939803, 9058.547072646474, 9319.487428191514, 9345.485998270024, 9470.810583788285, 9478.810423791485, 4075.449814275094, 4753.358296629911, 4860.295257187688, 4896.559309662076, 4897.608191344684, 2048.621005114059, 2073.450535608065, 2077.491014701408, 2085.843561732876, 2142.0039681548155, 942.6417961174847, 944.1211988143544, 956.1585804492141, 1009.5557954500143, 1069.5989004123453, 20647.0, 20932.16271349129, 20948.37154885356, 21202.78797212039, 21255.78744212569, 970.2529052629436, 999.7453196238674, 1014.8071866345371, 1036.1503567074926, 1070.7537266428765, 10832.566697332019, 11209.248278166477, 11219.66341009771, 11222.0, 11243.381614011145, 636950.0, 653314.0, 655667.0, 664885.6755716191, 665514.0, 95781.52109239549, 124449.0, 127666.0, 129467.0, 129688.0, 998.4009594243541, 1029.8352263637782, 1041.8437234414898, 1043.6503771236569, 1051.7265510967204, 18227.179776909805, 18864.811351886856, 19021.42935711931, 19135.52161195981, 19363.4190974271, 54639.72680136574, 55086.34740957778, 55562.44437555573, 58273.0, 58565.0, 81078.59460702895, 82288.0, 82720.0, 84405.57797210975, 85041.0, 5948.940510594839, 7793.961030194812, 7896.605169741605, 8178.713745018896, 8487.745367638981, 9153.679621213225, 11836.940815295868, 12489.875101248872, 12759.234445932902, 12831.03767217481, 8850.73447796567, 9950.651727189514, 11118.49966751482, 11180.888191117985, 11260.38067906273, 10214.029667181605, 10217.897821021694, 10879.45602719845, 10912.181586380895, 11053.0, 9113.179813816523, 9622.951885240529, 10548.0, 11124.332540047299, 11486.82769758471, 6934.965325173342, 8041.195880411912, 8079.474834135763, 8437.578121094044, 8653.39426240157, 10156.847647285149, 11390.715232119263, 11515.193936424705, 11533.135014873751, 11566.768664626828], "value": 9707.73906422612, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1634.8446897544734, 1655.7681924530564, 1980.7524059492564, 2058.60886431578, 2099.9790002099976, 8385.371097167706, 9054.4114632549, 9243.907560924388, 9386.342955993083, 9413.388129771569, 9010.54947252638, 9139.588718507666, 9148.130927561888, 9205.171534561889, 9303.488308143067, 2558.9232323030337, 2564.205096420106, 2573.7554932281496, 2583.935401614956, 2592.8703564821776, 2511.1336588876898, 2558.3859873630286, 2564.756348146923, 2576.768090871821, 2577.0464927976673, 2112.2501511963264, 2523.2808649534886, 2594.44219492809, 2597.0910181436534, 2599.54507961107, 2549.8725063746924, 2563.192594332779, 2575.7681808637217, 2575.948481030369, 2603.557395242813, 2182.6725991101302, 2555.0546297869723, 2566.0120853471362, 2577.278362058608, 2601.7268186840297, 9111.362204645677, 9303.674371396968, 9336.066393360677, 9373.10955459237, 9402.247820174438, 5323.22813192085, 5419.647722898038, 5608.523275521586, 5616.66300022, 5635.915461268084, 3514.1390359361862, 3529.170644898447, 3537.9292414151832, 3766.679832214265, 3768.321702093622, 1060.173065009294, 1074.3392813419691, 1077.439731339707, 1079.6868908016745, 1080.9136817498363, 12286.447109880077, 12287.13990020708, 12565.811512827315, 12585.811212831815, 12640.620781376481, 4981.9252711209365, 5014.07239660664, 5110.7955681773055, 5135.435102138762, 5198.948010519846, 10629.84055239172, 10646.0, 10681.198910081775, 10686.51910664022, 10692.62575809843, 5557.749901254453, 5572.275604171513, 5594.468525490072, 5596.076647353141, 5596.804111856147, 4662.883427914261, 5043.0, 5128.538431541179, 5154.819581314636, 5204.1673332266655, 4267.423897773823, 4293.0, 4315.697901146889, 4334.9349759752995, 4399.494058183286, 1415.9575212743634, 1438.6403399150213, 1448.8840892728556, 1450.9419623214953, 1452.556970124101, 873.3537182484915, 939.8073394954018, 975.3513913247752, 977.3207620703542, 997.9501024948723, 6006.519478441714, 6094.421030002142, 6148.569600128038, 6195.349488303754, 6208.2239720034995, 432.7317063420699, 439.81967393368564, 474.4780741184669, 486.84907678619743, 492.778249787597, 3903.6681882039775, 4019.9196016079513, 4035.1122752994293, 4038.63652271297, 4120.670346372283, 10840.349579025284, 11086.334819910831, 11407.942960285145, 11525.135614828921, 11536.25014374063, 10682.78634427307, 11189.72025699364, 11237.719057023476, 11422.828657570142, 11445.198836081561, 7280.8350663893525, 8276.462029968034, 8543.530105844115, 8791.384603077724, 8800.295976321879, 5838.241028666416, 5840.0, 5903.498202652822, 6055.878882422415, 6071.846349193639, 9862.556184971838, 9961.800763984824, 10039.749006274902, 10053.648122315684, 10057.29598928068, 9965.651202207888, 10230.539625716712, 10312.639057632947, 10343.172546196436, 10346.948265258625, 7107.111611048619, 7598.658060387185, 7843.882341764992, 7866.331361834308, 7981.122076571456, 8534.487930724177, 8624.827503449771, 8642.049374568667, 8679.218870301704, 8708.56457177126, 7209.35115839577, 7632.923670763444, 7774.144844067261, 7852.842943140991, 7881.487703299267, 7545.886811697938, 7592.240775922363, 7598.20218877032, 7608.771736847903, 7647.388208943383, 7044.929550704633, 7045.506814523139, 7085.362317391459, 7165.498415110892, 7197.064381630355, 4987.60099192071, 5002.474740152232, 5119.897602048012, 5165.535101840853, 5207.6875387476875, 5100.744962751922, 5106.489351065013, 5106.897862042812, 5192.792288308426, 5201.141811601195], "value": 4730.839898774375, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.15211970074813, 340.4263815470932, 342.0422816114879, 347.7009771596427, 349.32754447688194, 7022.824429389268, 7135.3934915532245, 7146.213916469184, 7188.029616001845, 7200.135983681959, 5657.9434205657935, 6060.757569697218, 6868.626274745046, 7186.35322820946, 7196.208417074117, 5297.284866543016, 5303.469653034705, 5312.57499400049, 5318.946810531884, 5320.574354051667, 4652.860049287912, 4974.676646017997, 5309.787608495655, 5325.893482130355, 5335.493128152839, 5244.292020577212, 5248.21276808478, 5253.133233016548, 5261.605379596526, 5277.736113194344, 5073.010762901217, 5241.9213711794355, 5261.657992230512, 5264.184051472028, 5272.367315922113, 4498.650404878524, 4513.52607976161, 4525.954740452619, 4531.56950089741, 4533.637309015271, 5495.258140151109, 6839.931600683979, 6879.552829066095, 6880.311968803129, 6927.7228910843505, 4953.876153096166, 6132.6933653317155, 6152.87694246117, 6163.383661633846, 6183.628982261079, 7646.694132234704, 7649.847003059907, 7659.195784442609, 7669.194734552903, 7690.115636701795, 4857.004314115633, 5038.496150384969, 5040.387076135638, 5041.697498150123, 5044.621653376012, 7003.82490437738, 7939.523628582304, 7963.761087167394, 7984.520928744294, 8008.959955200186, 5112.105381558213, 5127.9230811537855, 5154.175331946945, 5164.948350516522, 5177.042247184208, 5280.524752772269, 5282.207668849626, 5294.629375943647, 5298.91372268684, 5301.1783173608155, 4696.929546056812, 5288.206768984606, 5376.6827127354045, 5756.769729210785, 5889.852753681106, 7604.505707129019, 7608.80977975555, 7611.2769286917655, 7622.237776222444, 7637.809054773564, 1792.211426972128, 1834.3488061738158, 1835.1833434121954, 1839.9632007360046, 1843.8156184381455, 1088.646189988257, 1151.0561339701364, 1152.0840931459509, 1168.1647621950176, 1187.6021532786613, 7109.608971506616, 7121.679524421414, 7130.893036604455, 7160.785176444715, 7170.032045673872, 7905.169957154531, 7933.880991785128, 7955.0, 7963.681452741941, 7964.880526791987, 6216.533759968021, 6712.194536655535, 7110.360067593838, 7145.285471452813, 7184.461165412615, 3995.4006898965386, 4154.356074808409, 4177.97911010443, 4191.601797829201, 4210.873673789792, 8245.546494942715, 8287.0, 8287.62705678246, 8307.543085130375, 8316.958415207886, 8168.978877640295, 8186.386021048445, 8189.198376357192, 8196.467229630054, 8206.958965205136, 8244.010718713793, 8276.379271554657, 8290.917090829134, 8294.626741796514, 8295.460795048302, 7668.80827979305, 7868.213178682086, 7904.565248911135, 7927.0, 7935.841283174189, 7677.846443071219, 7697.961510192413, 7716.691332346643, 7718.228177182462, 7732.110807257238, 8226.424150309422, 8251.669933202846, 8268.297194738514, 8281.964754405699, 8292.751217463485, 5190.195519694454, 5199.064168449715, 5199.064168449715, 5213.739313034409, 5216.869578260422, 6761.290064543152, 7714.537127772127, 7864.72473463426, 8080.878786818085, 8086.3126634234395, 7682.423818213544, 7714.305712485679, 7716.729914453192, 7727.265909738566, 7749.961250193713, 7728.613569321624, 7743.148253692202, 7748.65131069092, 7767.495112817649, 7774.650140743794, 7292.744753933587, 7565.962170189114, 7584.051993500812, 7605.353544948742, 7619.314261716472, 7683.193264707125, 7747.3802095833325, 7751.612419379121, 7780.610969451618, 7787.961060194662, 6085.939140608537, 7238.8914166288605, 7443.404527637667, 7592.126905405949, 7602.885956710543], "value": 5565.595632428423, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [660.8215781738928, 729.6060127531132, 764.7878113190593, 775.8409526047161, 781.1173374087281, 15202.923985380072, 15250.084994900313, 15260.46588369406, 15272.236388180589, 15576.45482408116, 14356.64108397288, 14464.5660630181, 14479.855201447983, 14513.782293265584, 14540.781888271684, 785.2775446589152, 939.0468674295596, 946.8248374050822, 980.6224603527658, 984.3503287830033, 943.1558754914382, 943.9528023598792, 964.7009427077628, 974.5468357213894, 978.3102912446743, 1019.7042857571296, 1021.2289721260438, 1025.2361990317224, 1026.6406757634843, 1030.587764894041, 898.5013317608724, 954.7851733359938, 978.349397650565, 980.36276420327, 988.4662282367512, 757.7650928212272, 762.5843915066303, 762.5882023707192, 763.8510490454364, 765.7013764631798, 14729.852701472955, 14774.039687420283, 15125.0, 15285.541433757006, 15304.464343748026, 13137.86862131376, 13204.60386188416, 13306.13510121839, 13344.199348039148, 13424.328783560783, 10071.647492337806, 10078.647247346382, 10381.688549343457, 10412.68761937143, 10427.426491542961, 3585.713142948558, 3610.458431235336, 3709.7403181777286, 3746.1009357754215, 3868.168343806095, 77683.6115819423, 78902.6054869722, 79057.99999999942, 79556.20443795546, 80950.59524702397, 9659.32384733076, 9822.557984890696, 9895.554700038516, 10024.699259022242, 10045.748856278506, 17224.397146099825, 21585.892070539547, 21769.455763606038, 21912.12351505922, 21986.340409787732, 7777.455578109591, 7804.414668899855, 7820.296173344426, 7923.603819808986, 8161.020677518736, 4155.58444155582, 4166.812493437803, 4168.562300958356, 4169.60388763067, 4278.914421711548, 3660.432632941898, 3665.0104471792465, 3726.8136593170234, 3750.5686846012513, 3756.4740936268945, 634.213575166799, 641.5060403489288, 653.5261935097017, 655.9606423614598, 669.7957123077508, 578.9131630255496, 586.1325238646826, 587.4507335641189, 594.1622312539299, 609.6220343387129, 8354.415190936697, 8362.665493380196, 8366.163383661707, 8405.70580029696, 8543.444676096035, 507.68269831355406, 594.337313895004, 595.6604735300923, 605.4762630324825, 612.0788213738296, 3529.5235143255845, 3570.892873213808, 3762.6425489578446, 3849.8075096245075, 4001.099752555652, 87160.69258961121, 87519.12480875109, 87906.56046719853, 87944.6808297876, 89469.10530894608, 70954.0, 71480.0, 71951.28048719445, 72634.63682681553, 73177.0, 69369.0, 69953.60092798116, 71632.64183679153, 71952.2804771956, 72754.9086763699, 6888.207856096613, 6889.483288753263, 6921.861562768817, 7159.677814498465, 7214.0, 50320.0, 50408.49591504038, 50856.0, 51022.744886276814, 51369.486305136474, 65189.34810652022, 68651.65674171597, 70352.29647703457, 70882.29117708757, 71436.64281678558, 9742.634237717042, 9796.853047204155, 9914.752131196778, 10053.547590358305, 10134.189264858942, 10650.201234907257, 10965.451727413438, 11153.442327883411, 11155.776884462428, 11167.553297868319, 10326.277160598986, 10394.376337419779, 10432.374057556572, 10537.42044187577, 10669.199810014436, 9883.0, 10152.949235254073, 10225.43760093202, 10359.792804144026, 11120.88879111231, 10057.145142662956, 10062.547185376821, 10191.796164076824, 10203.489825508546, 10816.0, 7898.921010789819, 8738.169873861973, 8769.517676527852, 9063.0, 9083.636654533744, 7407.407407407287, 7474.850502990019, 8859.202671759573, 9054.643010302267, 9104.635814567344], "value": 6845.114753669532, "unit": "score"}], "name": "dromaeo_css", "value": 7669.395617328658, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 122.0, 113.0, 116.0, 116.0, 131.0, 118.0, 115.0, 115.0, 142.0], "value": 120.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 118.0, 147.0, 153.0, 123.0, 127.0, 153.0, 177.0, 157.0, 115.0], "value": 144.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 197.0, 201.0, 195.0, 195.0, 195.0, 195.0, 193.0, 197.0, 222.0], "value": 198.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 112.0, 179.0, 84.0, 55.0, 56.0, 58.0, 124.0, 69.0], "value": 87.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 81.0, 124.0, 77.0, 75.0, 83.0, 118.0, 80.0, 78.0, 119.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 101.0, 81.0, 84.0, 130.0, 134.0, 122.0, 91.0, 88.0], "value": 100.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 119.0, 119.0, 96.0, 95.0, 96.0, 96.0, 96.0], "value": 100.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 93.0, 121.0, 149.0, 96.0, 89.0, 90.0, 91.0, 88.0, 89.0], "value": 99.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 64.0, 66.0, 64.0, 65.0, 65.0, 64.0, 79.0, 62.0, 60.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 67.0, 71.0, 75.0, 72.0, 73.0, 66.0, 67.0, 66.0, 68.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 103.0, 103.0, 107.0, 101.0, 80.0, 79.0, 86.0, 84.0], "value": 90.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 127.0, 128.0, 127.0, 127.0, 129.0, 150.0, 139.0, 175.0, 129.0], "value": 135.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 151.0, 221.0, 214.0, 168.0, 166.0, 221.0, 210.0, 183.0, 165.0], "value": 188.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 101.0, 93.0, 68.0, 71.0, 68.0, 67.0, 65.0, 68.0, 75.0], "value": 77.8, "unit": "score"}], "name": "kraken", "value": 1568.8999999999999, "alertThreshold": 2.0}]} 10:16:26 INFO - Return code: 0 10:16:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:16:26 ERROR - # TBPL SUCCESS # 10:16:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:16:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:16:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:16:26 INFO - 2016-07-15 10:16:26.907 screenresolution[1931:17385] starting screenresolution argv=screenresolution get 10:16:26 INFO - 2016-07-15 10:16:26.910 screenresolution[1931:17385] Display 0: 1600x1200x32@60 10:16:26 INFO - 2016-07-15 10:16:26.916 screenresolution[1932:17388] starting screenresolution argv=screenresolution list 10:16:26 INFO - Available Modes on Display 0 10:16:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:16:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:16:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:16:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:16:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:16:27 INFO - Intel Iris: 10:16:27 INFO - Chipset Model: Intel Iris 10:16:27 INFO - Type: GPU 10:16:27 INFO - Bus: Built-In 10:16:27 INFO - VRAM (Dynamic, Max): 1536 MB 10:16:27 INFO - Vendor: Intel (0x8086) 10:16:27 INFO - Device ID: 0x0a2e 10:16:27 INFO - Revision ID: 0x0009 10:16:27 INFO - Displays: 10:16:27 INFO - Display: 10:16:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:16:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:16:27 INFO - Display Serial Number: 1600x1200 60 10:16:27 INFO - Main Display: Yes 10:16:27 INFO - Mirror: Off 10:16:27 INFO - Online: Yes 10:16:27 INFO - Rotation: Supported 10:16:27 INFO - Return code: 0 10:16:27 INFO - Running post-action listener: _resource_record_post_action 10:16:27 INFO - Running post-run listener: _resource_record_post_run 10:16:28 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 31632896; Write bytes: 497114112; Read time: 196; Write time: 1575 10:16:28 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 189235712; Write bytes: 184832000; Read time: 17238; Write time: 422 10:16:28 INFO - run-tests - Wall time: 1172s; CPU: 54.0%; Read bytes: 29851136; Write bytes: 301489152; Read time: 181; Write time: 1118 10:16:28 INFO - Running post-run listener: _upload_blobber_files 10:16:28 INFO - Blob upload gear active. 10:16:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:16:28 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 10:16:28 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'] 10:16:28 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 10:16:28 INFO - (blobuploader) - INFO - Open directory for files ... 10:16:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:16:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:16:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:16:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:16:29 INFO - (blobuploader) - INFO - Done attempting. 10:16:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:16:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:16:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:16:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:16:30 INFO - (blobuploader) - INFO - Done attempting. 10:16:30 INFO - (blobuploader) - INFO - Iteration through files over. 10:16:30 INFO - Return code: 0 10:16:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:16:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:16:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d3364ac4ae671db7ac914f6cb2565c7242afc048cf93d8e600998a91b1b331fefa01751d4a558178f3e4c6bdf7837425c7583b788c5558ed08005bc4dbdd8ca7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/be41af404002a697f8d02a60c826ac63c03e25ace86d2ee49072fb96ebf747f8f3e06342c00fa7120295eda4922c966e3cd4bdb8cffb5a9b8abeaa0c236958f2"} 10:16:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:16:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:16:30 INFO - Contents: 10:16:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d3364ac4ae671db7ac914f6cb2565c7242afc048cf93d8e600998a91b1b331fefa01751d4a558178f3e4c6bdf7837425c7583b788c5558ed08005bc4dbdd8ca7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/be41af404002a697f8d02a60c826ac63c03e25ace86d2ee49072fb96ebf747f8f3e06342c00fa7120295eda4922c966e3cd4bdb8cffb5a9b8abeaa0c236958f2"} 10:16:30 INFO - Running post-run listener: copy_logs_to_upload_dir 10:16:30 INFO - Copying logs to upload dir... 10:16:30 INFO - mkdir: /builds/slave/test/build/upload/logs 10:16:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.417878 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-07-15 10:16:30.913555) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 10:16:30.917149) ========= 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.81jkyUMYeO/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.gvepObAYpo/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d3364ac4ae671db7ac914f6cb2565c7242afc048cf93d8e600998a91b1b331fefa01751d4a558178f3e4c6bdf7837425c7583b788c5558ed08005bc4dbdd8ca7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/be41af404002a697f8d02a60c826ac63c03e25ace86d2ee49072fb96ebf747f8f3e06342c00fa7120295eda4922c966e3cd4bdb8cffb5a9b8abeaa0c236958f2"} build_url:https://queue.taskcluster.net/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011000 build_url: 'https://queue.taskcluster.net/v1/task/TunlMASUTGe43JL3Z7bYVQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d3364ac4ae671db7ac914f6cb2565c7242afc048cf93d8e600998a91b1b331fefa01751d4a558178f3e4c6bdf7837425c7583b788c5558ed08005bc4dbdd8ca7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/be41af404002a697f8d02a60c826ac63c03e25ace86d2ee49072fb96ebf747f8f3e06342c00fa7120295eda4922c966e3cd4bdb8cffb5a9b8abeaa0c236958f2"}' ========= master_lag: 0.07 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 10:16:31.002107) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 10:16:31.002423) ========= 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.81jkyUMYeO/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.gvepObAYpo/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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.004475 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 10:16:31.060015) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 10:16:31.060313) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 10:16:31.060675) ========= ========= Total master_lag: 0.37 =========