builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0244 starttime: 1464269147.46 results: success (0) buildid: 20160526052739 builduid: 5abc185371e04a09b9188384b544af66 revision: e6b03d71815222297fdf701695ddd2cc743b0a80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 06:25:47.463314) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 06:25:47.463792) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 06:25:47.464083) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 06:25:47.486757) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 06:25:47.487108) ========= 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.zfyCSwyxNq/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.H6Tw9W5ngG/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-26 06:25:47-- 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% 51.9M=0s 2016-05-26 06:25:47 (51.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114047 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 06:25:47.622533) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 06:25:47.622935) ========= 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.zfyCSwyxNq/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.H6Tw9W5ngG/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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.026107 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 06:25:47.671606) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 06:25:47.671953) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e6b03d71815222297fdf701695ddd2cc743b0a80 --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-aurora --rev e6b03d71815222297fdf701695ddd2cc743b0a80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zfyCSwyxNq/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.H6Tw9W5ngG/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-26 06:25:47,748 truncating revision to first 12 chars 2016-05-26 06:25:47,748 Setting DEBUG logging. 2016-05-26 06:25:47,748 attempt 1/10 2016-05-26 06:25:47,748 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e6b03d718152?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 06:25:49,289 unpacking tar archive at: mozilla-aurora-e6b03d718152/testing/mozharness/ program finished with exit code 0 elapsedTime=1.814398 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 06:25:49.507128) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 06:25:49.507441) ========= script_repo_revision: e6b03d71815222297fdf701695ddd2cc743b0a80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 06:25:49.510918) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 06:25:49.511192) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 06:25:49.528473) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-05-26 06:25:49.528817) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zfyCSwyxNq/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.H6Tw9W5ngG/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 06:25:49 INFO - MultiFileLogger online at 20160526 06:25:49 in /builds/slave/test 06:25:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 06:25:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:25:49 INFO - {'append_to_log': False, 06:25:49 INFO - 'base_work_dir': '/builds/slave/test', 06:25:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:25:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:25:49 INFO - 'branch': 'Mozilla-Aurora', 06:25:49 INFO - 'buildbot_json_path': 'buildprops.json', 06:25:49 INFO - 'config_files': ('talos/mac_config.py',), 06:25:49 INFO - 'default_actions': ('clobber', 06:25:49 INFO - 'read-buildbot-config', 06:25:49 INFO - 'download-and-extract', 06:25:49 INFO - 'populate-webroot', 06:25:49 INFO - 'create-virtualenv', 06:25:49 INFO - 'install', 06:25:49 INFO - 'run-tests'), 06:25:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:25:49 INFO - 'download_minidump_stackwalk': True, 06:25:49 INFO - 'download_symbols': 'ondemand', 06:25:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:25:49 INFO - 'tooltool.py': '/tools/tooltool.py', 06:25:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:25:49 INFO - '/tools/misc-python/virtualenv.py')}, 06:25:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:25:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:25:49 INFO - 'installer_path': 'installer.exe', 06:25:49 INFO - 'log_level': 'info', 06:25:49 INFO - 'log_name': 'talos', 06:25:49 INFO - 'log_to_console': True, 06:25:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:25:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:25:49 INFO - 'opt_config_files': (), 06:25:49 INFO - 'pip_index': False, 06:25:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:25:49 INFO - 'cmd': ('bash', 06:25:49 INFO - '-c', 06:25:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:25:49 INFO - 'enabled': True, 06:25:49 INFO - 'halt_on_failure': False, 06:25:49 INFO - 'name': 'check_screen_resolution'},), 06:25:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:25:49 INFO - 'cmd': ('bash', 06:25:49 INFO - '-c', 06:25:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:25:49 INFO - 'enabled': True, 06:25:49 INFO - 'halt_on_failure': False, 06:25:49 INFO - 'name': 'check_screen_resolution'},), 06:25:49 INFO - 'run_cmd_checks_enabled': True, 06:25:49 INFO - 'sps_profile': False, 06:25:49 INFO - 'sps_profile_interval': 0, 06:25:49 INFO - 'suite': 'dromaeojs-e10s', 06:25:49 INFO - 'system_bits': '32', 06:25:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:25:49 INFO - 'title': 't-yosemite-r7-0244', 06:25:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:25:49 INFO - 'use_talos_json': True, 06:25:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:25:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:25:49 INFO - 'work_dir': 'build'} 06:25:49 INFO - ##### 06:25:49 INFO - ##### Running clobber step. 06:25:49 INFO - ##### 06:25:49 INFO - Running pre-action listener: _resource_record_pre_action 06:25:49 INFO - Running main action method: clobber 06:25:49 INFO - rmtree: /builds/slave/test/build 06:25:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:25:51 INFO - Running post-action listener: _resource_record_post_action 06:25:51 INFO - ##### 06:25:51 INFO - ##### Running read-buildbot-config step. 06:25:51 INFO - ##### 06:25:51 INFO - Running pre-action listener: _resource_record_pre_action 06:25:51 INFO - Running main action method: read_buildbot_config 06:25:51 INFO - Using buildbot properties: 06:25:51 INFO - { 06:25:51 INFO - "project": "", 06:25:51 INFO - "product": "firefox", 06:25:51 INFO - "script_repo_revision": "production", 06:25:51 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 06:25:51 INFO - "repository": "", 06:25:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 06:25:51 INFO - "buildid": "20160526052739", 06:25:51 INFO - "pgo_build": "False", 06:25:51 INFO - "basedir": "/builds/slave/test", 06:25:51 INFO - "buildnumber": 12, 06:25:51 INFO - "slavename": "t-yosemite-r7-0244", 06:25:51 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 06:25:51 INFO - "platform": "yosemite_r7", 06:25:51 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 06:25:51 INFO - "branch": "mozilla-aurora", 06:25:51 INFO - "repo_path": "releases/mozilla-aurora", 06:25:51 INFO - "revision": "e6b03d71815222297fdf701695ddd2cc743b0a80", 06:25:51 INFO - "stage_platform": "macosx64", 06:25:51 INFO - "builduid": "5abc185371e04a09b9188384b544af66", 06:25:51 INFO - "slavebuilddir": "test" 06:25:51 INFO - } 06:25:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 06:25:51 INFO - Running post-action listener: _resource_record_post_action 06:25:51 INFO - ##### 06:25:51 INFO - ##### Running download-and-extract step. 06:25:51 INFO - ##### 06:25:51 INFO - Running pre-action listener: _resource_record_pre_action 06:25:51 INFO - Running main action method: download_and_extract 06:25:51 INFO - mkdir: /builds/slave/test/build/tests 06:25:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:25:51 INFO - https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:25:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 06:25:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 06:25:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 06:25:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 06:25:53 INFO - Downloaded 1199 bytes. 06:25:53 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 06:25:53 INFO - Using the following test package requirements: 06:25:53 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 06:25:53 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:25:53 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 06:25:53 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:25:53 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 06:25:53 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:25:53 INFO - u'jsshell-mac.zip'], 06:25:53 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:25:53 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 06:25:53 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 06:25:53 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:25:53 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 06:25:53 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:25:53 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 06:25:53 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:25:53 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 06:25:53 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:25:53 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 06:25:53 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 06:25: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')]} 06:25:53 INFO - https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:25:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:25:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:25:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:25:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 06:25:55 INFO - Downloaded 18425406 bytes. 06:25:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:25:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:25:58 INFO - Return code: 0 06:25:58 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 06:25:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:25:58 INFO - https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:25:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:25:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:25:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 06:25:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 06:25:59 INFO - Downloaded 18425406 bytes. 06:25:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:25:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:26:02 INFO - Return code: 0 06:26: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')]} 06:26:02 INFO - https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:26:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 06:26:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 06:26:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 06:26:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 06:26:03 INFO - Downloaded 10840145 bytes. 06:26:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:26:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 06:26:04 INFO - Return code: 0 06:26:04 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')]} 06:26:04 INFO - https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 06:26:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:26:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:26:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 06:26:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 06:26:08 INFO - Downloaded 114133909 bytes. 06:26:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:26:08 INFO - mkdir: /builds/slave/test/properties 06:26:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:26:08 INFO - Writing to file /builds/slave/test/properties/build_url 06:26:08 INFO - Contents: 06:26:08 INFO - build_url:https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:26:09 INFO - Running post-action listener: _resource_record_post_action 06:26:09 INFO - Running post-action listener: set_extra_try_arguments 06:26:09 INFO - ##### 06:26:09 INFO - ##### Running populate-webroot step. 06:26:09 INFO - ##### 06:26:09 INFO - Running pre-action listener: _resource_record_pre_action 06:26:09 INFO - Running main action method: populate_webroot 06:26:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:26:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:26:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:26:09 INFO - '--tppagecycles', 06:26:09 INFO - '7'], 06:26:09 INFO - 'tests': ['tsvgm']}}, 06:26:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:26:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:26:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:26:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:26:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:26:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:26:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:09 INFO - 'talos_options': ['--disable-e10s'], 06:26:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:26:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:26:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:09 INFO - 'talos_options': ['--disable-e10s'], 06:26:09 INFO - 'tests': ['damp', 'tps']}, 06:26:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:09 INFO - 'tests': ['damp', 'tps']}, 06:26:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:26:09 INFO - 'tests': ['dromaeo_dom']}, 06:26:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:26:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:26:09 INFO - 'tests': ['a11yr', 06:26:09 INFO - 'ts_paint', 06:26:09 INFO - 'tpaint', 06:26:09 INFO - 'sessionrestore', 06:26:09 INFO - 'sessionrestore_no_auto_restore', 06:26:09 INFO - 'tabpaint']}, 06:26:09 INFO - 'other-e10s': {'tests': ['a11yr', 06:26:09 INFO - 'ts_paint', 06:26:09 INFO - 'tpaint', 06:26:09 INFO - 'sessionrestore', 06:26:09 INFO - 'sessionrestore_no_auto_restore', 06:26:09 INFO - 'tabpaint']}, 06:26:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:26:09 INFO - 'ts_paint', 06:26:09 INFO - 'tpaint', 06:26:09 INFO - 'sessionrestore', 06:26:09 INFO - 'sessionrestore_no_auto_restore', 06:26:09 INFO - 'tabpaint']}, 06:26:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:26:09 INFO - 'ts_paint', 06:26:09 INFO - 'tpaint', 06:26:09 INFO - 'sessionrestore', 06:26:09 INFO - 'sessionrestore_no_auto_restore', 06:26:09 INFO - 'tabpaint']}, 06:26:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:26:09 INFO - 'tests': ['a11yr', 06:26:09 INFO - 'ts_paint', 06:26:09 INFO - 'tpaint', 06:26:09 INFO - 'sessionrestore', 06:26:09 INFO - 'sessionrestore_no_auto_restore', 06:26:09 INFO - 'tabpaint']}, 06:26:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:26:09 INFO - 'tests': ['a11yr', 06:26:09 INFO - 'ts_paint', 06:26:09 INFO - 'tpaint', 06:26:09 INFO - 'sessionrestore', 06:26:09 INFO - 'sessionrestore_no_auto_restore', 06:26:09 INFO - 'tabpaint']}, 06:26:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:26:09 INFO - 'tests': ['tsvgx', 06:26:09 INFO - 'tsvgr_opacity', 06:26:09 INFO - 'tart', 06:26:09 INFO - 'tscrollx', 06:26:09 INFO - 'cart']}, 06:26:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:26:09 INFO - 'tsvgr_opacity', 06:26:09 INFO - 'tart', 06:26:09 INFO - 'tscrollx', 06:26:09 INFO - 'cart']}, 06:26:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:09 INFO - 'talos_options': ['--disable-e10s'], 06:26:09 INFO - 'tests': ['tp5o']}, 06:26:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:09 INFO - 'tests': ['tp5o']}, 06:26:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:09 INFO - 'talos_options': ['--disable-e10s', 06:26:09 INFO - '--xperf_path', 06:26:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:26:09 INFO - 'tests': ['tp5n']}, 06:26:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:09 INFO - 'talos_options': ['--xperf_path', 06:26:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:26:09 INFO - 'tests': ['tp5n']}}, 06:26:09 INFO - 'talos.zip': {'path': '', 06:26:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:26:09 INFO - Running post-action listener: _resource_record_post_action 06:26:09 INFO - ##### 06:26:09 INFO - ##### Running create-virtualenv step. 06:26:09 INFO - ##### 06:26:09 INFO - Running pre-action listener: _resource_record_pre_action 06:26:09 INFO - Running main action method: create_virtualenv 06:26:09 INFO - Creating virtualenv /builds/slave/test/build/venv 06:26:09 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 06:26:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:26:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:26:09 INFO - Using real prefix '/tools/python27' 06:26:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:26:10 INFO - Installing distribute.............................................................................................................................................................................................done. 06:26:13 INFO - Installing pip.................done. 06:26:13 INFO - Return code: 0 06:26:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:26:13 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')]} 06:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:13 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')]} 06:26:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:13 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 0x10294aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10295a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e42c94a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e9e00>, '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 0x102652db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027e4b20>, '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.zfyCSwyxNq/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.H6Tw9W5ngG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 06:26:13 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 06:26:13 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 06:26:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zfyCSwyxNq/Render', 06:26:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:13 INFO - 'HOME': '/Users/cltbld', 06:26:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:13 INFO - 'LOGNAME': 'cltbld', 06:26:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:13 INFO - 'MOZ_NO_REMOTE': '1', 06:26:13 INFO - 'NO_EM_RESTART': '1', 06:26:13 INFO - 'PAGER': '/bin/cat', 06:26:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:13 INFO - 'PWD': '/builds/slave/test', 06:26:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:13 INFO - 'SHELL': '/bin/bash', 06:26:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H6Tw9W5ngG/Listeners', 06:26:13 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 06:26:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:13 INFO - 'USER': 'cltbld', 06:26:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:13 INFO - 'XPC_FLAGS': '0x0', 06:26:13 INFO - 'XPC_SERVICE_NAME': '0', 06:26:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:13 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 06:26:13 INFO - Cleaning up... 06:26:13 INFO - Return code: 0 06:26:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 06:26:13 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')]} 06:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:13 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')]} 06:26:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:13 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 0x10294aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10295a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e42c94a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e9e00>, '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 0x102652db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027e4b20>, '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.zfyCSwyxNq/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.H6Tw9W5ngG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 06:26:13 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 06:26:13 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 06:26:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zfyCSwyxNq/Render', 06:26:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:13 INFO - 'HOME': '/Users/cltbld', 06:26:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:13 INFO - 'LOGNAME': 'cltbld', 06:26:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:13 INFO - 'MOZ_NO_REMOTE': '1', 06:26:13 INFO - 'NO_EM_RESTART': '1', 06:26:13 INFO - 'PAGER': '/bin/cat', 06:26:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:13 INFO - 'PWD': '/builds/slave/test', 06:26:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:13 INFO - 'SHELL': '/bin/bash', 06:26:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H6Tw9W5ngG/Listeners', 06:26:13 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 06:26:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:13 INFO - 'USER': 'cltbld', 06:26:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:13 INFO - 'XPC_FLAGS': '0x0', 06:26:13 INFO - 'XPC_SERVICE_NAME': '0', 06:26:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:13 INFO - Downloading/unpacking psutil>=0.7.1 06:26: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 06:26: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 06:26: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 06:26: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 06:26: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 06:26: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 06:26:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:26:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:26:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:26:16 INFO - Installing collected packages: psutil 06:26:16 INFO - Running setup.py install for psutil 06:26:17 INFO - building 'psutil._psutil_osx' extension 06:26:17 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 06:26:17 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 06:26:17 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 06:26:17 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 06:26:17 INFO - building 'psutil._psutil_posix' extension 06:26:17 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 06:26:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:26:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:26:17 INFO - ^ 06:26:17 INFO - 1 warning generated. 06:26:17 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 06:26:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:26:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:26:18 INFO - Successfully installed psutil 06:26:18 INFO - Cleaning up... 06:26:18 INFO - Return code: 0 06:26:18 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 06:26:18 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')]} 06:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:18 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')]} 06:26:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:18 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 0x10294aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10295a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e42c94a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e9e00>, '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 0x102652db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027e4b20>, '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.zfyCSwyxNq/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.H6Tw9W5ngG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 06:26:18 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 06:26:18 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 06:26:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zfyCSwyxNq/Render', 06:26:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:18 INFO - 'HOME': '/Users/cltbld', 06:26:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:18 INFO - 'LOGNAME': 'cltbld', 06:26:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:18 INFO - 'MOZ_NO_REMOTE': '1', 06:26:18 INFO - 'NO_EM_RESTART': '1', 06:26:18 INFO - 'PAGER': '/bin/cat', 06:26:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:18 INFO - 'PWD': '/builds/slave/test', 06:26:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:18 INFO - 'SHELL': '/bin/bash', 06:26:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H6Tw9W5ngG/Listeners', 06:26:18 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 06:26:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:18 INFO - 'USER': 'cltbld', 06:26:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:18 INFO - 'XPC_FLAGS': '0x0', 06:26:18 INFO - 'XPC_SERVICE_NAME': '0', 06:26:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:18 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:26:18 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 06:26:18 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 06:26:18 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 06:26:18 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 06:26:18 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 06:26:18 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 06:26:20 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:26:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:26:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:26:20 INFO - Installing collected packages: mozsystemmonitor 06:26:20 INFO - Running setup.py install for mozsystemmonitor 06:26:21 INFO - Successfully installed mozsystemmonitor 06:26:21 INFO - Cleaning up... 06:26:21 INFO - Return code: 0 06:26:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:21 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 0x10294aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10295a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e42c94a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e9e00>, '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 0x102652db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027e4b20>, '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.zfyCSwyxNq/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.H6Tw9W5ngG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 06:26:21 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 06:26:21 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 06:26:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zfyCSwyxNq/Render', 06:26:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:21 INFO - 'HOME': '/Users/cltbld', 06:26:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:21 INFO - 'LOGNAME': 'cltbld', 06:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:21 INFO - 'MOZ_NO_REMOTE': '1', 06:26:21 INFO - 'NO_EM_RESTART': '1', 06:26:21 INFO - 'PAGER': '/bin/cat', 06:26:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:21 INFO - 'PWD': '/builds/slave/test', 06:26:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:21 INFO - 'SHELL': '/bin/bash', 06:26:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H6Tw9W5ngG/Listeners', 06:26:21 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 06:26:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:21 INFO - 'USER': 'cltbld', 06:26:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:21 INFO - 'XPC_FLAGS': '0x0', 06:26:21 INFO - 'XPC_SERVICE_NAME': '0', 06:26:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:21 INFO - Downloading/unpacking blobuploader==1.2.4 06:26: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 06:26: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 06:26: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 06:26: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 06:26: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 06:26: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 06:26:23 INFO - Downloading blobuploader-1.2.4.tar.gz 06:26:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:26:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:26:23 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 06:26:23 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 06:26:23 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 06:26:23 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 06:26:23 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 06:26:23 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 06:26:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:26:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:26:24 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 06:26:24 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 06:26:24 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 06:26:24 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 06:26:24 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 06:26:24 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 06:26:24 INFO - Downloading docopt-0.6.1.tar.gz 06:26:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:26:25 INFO - Installing collected packages: blobuploader, requests, docopt 06:26:25 INFO - Running setup.py install for blobuploader 06:26:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:26:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:26:25 INFO - Running setup.py install for requests 06:26:25 INFO - Running setup.py install for docopt 06:26:25 INFO - Successfully installed blobuploader requests docopt 06:26:25 INFO - Cleaning up... 06:26:25 INFO - Return code: 0 06:26:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:26: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')]} 06:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26: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')]} 06:26:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:25 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 0x10294aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10295a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e42c94a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e9e00>, '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 0x102652db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027e4b20>, '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.zfyCSwyxNq/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.H6Tw9W5ngG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 06:26:25 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 06:26:25 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 06:26:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zfyCSwyxNq/Render', 06:26:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:25 INFO - 'HOME': '/Users/cltbld', 06:26:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:25 INFO - 'LOGNAME': 'cltbld', 06:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:25 INFO - 'MOZ_NO_REMOTE': '1', 06:26:25 INFO - 'NO_EM_RESTART': '1', 06:26:25 INFO - 'PAGER': '/bin/cat', 06:26:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:25 INFO - 'PWD': '/builds/slave/test', 06:26:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:25 INFO - 'SHELL': '/bin/bash', 06:26:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H6Tw9W5ngG/Listeners', 06:26:25 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 06:26:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:25 INFO - 'USER': 'cltbld', 06:26:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:25 INFO - 'XPC_FLAGS': '0x0', 06:26:25 INFO - 'XPC_SERVICE_NAME': '0', 06:26:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:26:26 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-cvBEde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:26:26 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-Vnt4_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:26:26 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-KvH1ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:26:26 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-JwaMkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:26:26 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-2U6huG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:26:26 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-PQsucS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:26:26 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-Tx6vPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:26:26 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-znR68l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:26:27 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-QdpIg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:26:27 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-trB5kZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:26:27 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-7iF5oO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:26:27 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-BDVaqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:26:27 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-0lPA8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:26:27 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-n2fHgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:26:27 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-MQTSoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:26:27 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-YjQWI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:26:27 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-z5fqpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:26:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:26:27 INFO - Running setup.py install for manifestparser 06:26:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:26:28 INFO - Running setup.py install for mozcrash 06:26:28 INFO - Running setup.py install for mozdebug 06:26:28 INFO - Running setup.py install for mozdevice 06:26:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:26:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:26:28 INFO - Running setup.py install for mozfile 06:26:28 INFO - Running setup.py install for mozhttpd 06:26:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:26:28 INFO - Running setup.py install for mozinfo 06:26:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:26:29 INFO - Running setup.py install for mozInstall 06:26:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:26:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:26:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:26:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:26:29 INFO - Running setup.py install for mozleak 06:26:29 INFO - Running setup.py install for mozlog 06:26:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:26:29 INFO - Running setup.py install for moznetwork 06:26:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:26:29 INFO - Running setup.py install for mozprocess 06:26:29 INFO - Running setup.py install for mozprofile 06:26:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:26:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:26:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:26:29 INFO - Running setup.py install for mozrunner 06:26:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:26:30 INFO - Running setup.py install for mozscreenshot 06:26:30 INFO - Running setup.py install for moztest 06:26:30 INFO - Running setup.py install for mozversion 06:26:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:26:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:26:30 INFO - Cleaning up... 06:26:30 INFO - Return code: 0 06:26:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:26:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:30 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 0x10294aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10295a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e42c94a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e9e00>, '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 0x102652db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027e4b20>, '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.zfyCSwyxNq/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.H6Tw9W5ngG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 06:26:30 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 06:26:30 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 06:26:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zfyCSwyxNq/Render', 06:26:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:30 INFO - 'HOME': '/Users/cltbld', 06:26:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:30 INFO - 'LOGNAME': 'cltbld', 06:26:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:30 INFO - 'MOZ_NO_REMOTE': '1', 06:26:30 INFO - 'NO_EM_RESTART': '1', 06:26:30 INFO - 'PAGER': '/bin/cat', 06:26:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:30 INFO - 'PWD': '/builds/slave/test', 06:26:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:30 INFO - 'SHELL': '/bin/bash', 06:26:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H6Tw9W5ngG/Listeners', 06:26:30 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 06:26:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:30 INFO - 'USER': 'cltbld', 06:26:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:30 INFO - 'XPC_FLAGS': '0x0', 06:26:30 INFO - 'XPC_SERVICE_NAME': '0', 06:26:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:26:30 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-LEtI9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:26:30 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)) 06:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:26:30 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-t0c4nF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:26:31 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)) 06:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:26:31 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-HhMY99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:26:31 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)) 06:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:26:31 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-QqaKg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:26:31 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)) 06:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:26:31 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-oJQoEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:26:31 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)) 06:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:26:31 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-RXizdh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:26:31 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)) 06:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:26:31 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-oSx0EK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:26:31 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)) 06:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:26:31 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-zpRq5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:26:31 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)) 06:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:26:31 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-CAHlaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:26:31 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)) 06:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:26:31 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-5DWMbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:26:31 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)) 06:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:26:31 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-IP9_0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:26:32 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-XMjOxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:26:32 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-4GZkPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:26:32 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-OdUMdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:26:32 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-HQMIhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:26:32 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-u33OmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:26:32 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)) 06:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:26:32 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-j6kdbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:26:32 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)) 06:26:32 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)) 06:26:32 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)) 06:26:32 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)) 06:26:32 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)) 06:26:32 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)) 06:26:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:26: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 06:26: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 06:26: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 06:26: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 06:26: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 06:26: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 06:26:35 INFO - Downloading blessings-1.6.tar.gz 06:26:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:26:35 INFO - Installing collected packages: blessings 06:26:35 INFO - Running setup.py install for blessings 06:26:35 INFO - Successfully installed blessings 06:26:35 INFO - Cleaning up... 06:26:35 INFO - Return code: 0 06:26:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:26:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:26:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:26:35 INFO - Reading from file tmpfile_stdout 06:26:35 INFO - Current package versions: 06:26:35 INFO - blessings == 1.6 06:26:35 INFO - blobuploader == 1.2.4 06:26:35 INFO - docopt == 0.6.1 06:26:35 INFO - manifestparser == 1.1 06:26:35 INFO - mozInstall == 1.12 06:26:35 INFO - mozcrash == 0.17 06:26:35 INFO - mozdebug == 0.1 06:26:35 INFO - mozdevice == 0.48 06:26:35 INFO - mozfile == 1.2 06:26:35 INFO - mozhttpd == 0.7 06:26:35 INFO - mozinfo == 0.9 06:26:35 INFO - mozleak == 0.1 06:26:35 INFO - mozlog == 3.1 06:26:35 INFO - moznetwork == 0.27 06:26:35 INFO - mozprocess == 0.22 06:26:35 INFO - mozprofile == 0.28 06:26:35 INFO - mozrunner == 6.11 06:26:35 INFO - mozscreenshot == 0.1 06:26:35 INFO - mozsystemmonitor == 0.0 06:26:35 INFO - moztest == 0.7 06:26:35 INFO - mozversion == 1.4 06:26:35 INFO - psutil == 3.1.1 06:26:35 INFO - requests == 1.2.3 06:26:35 INFO - wsgiref == 0.1.2 06:26:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:26: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')]} 06:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26: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')]} 06:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:35 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 0x10294aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10295a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e42c94a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e9e00>, '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 0x102652db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027e4b20>, '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.zfyCSwyxNq/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.H6Tw9W5ngG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 06:26:35 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 06:26:35 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 06:26:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zfyCSwyxNq/Render', 06:26:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:35 INFO - 'HOME': '/Users/cltbld', 06:26:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:35 INFO - 'LOGNAME': 'cltbld', 06:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:35 INFO - 'MOZ_NO_REMOTE': '1', 06:26:35 INFO - 'NO_EM_RESTART': '1', 06:26:35 INFO - 'PAGER': '/bin/cat', 06:26:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:35 INFO - 'PWD': '/builds/slave/test', 06:26:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:35 INFO - 'SHELL': '/bin/bash', 06:26:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H6Tw9W5ngG/Listeners', 06:26:35 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 06:26:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:35 INFO - 'USER': 'cltbld', 06:26:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:35 INFO - 'XPC_FLAGS': '0x0', 06:26:35 INFO - 'XPC_SERVICE_NAME': '0', 06:26:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:36 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)) 06:26:36 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)) 06:26:36 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)) 06:26:36 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)) 06:26:36 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)) 06:26:36 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)) 06:26:36 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)) 06:26:36 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)) 06:26:36 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)) 06:26:36 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)) 06:26:36 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)) 06:26:36 INFO - Cleaning up... 06:26:36 INFO - Return code: 0 06:26:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:26:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:26:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10294aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10295a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e42c94a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e9e00>, '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 0x102652db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027e4b20>, '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.zfyCSwyxNq/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.H6Tw9W5ngG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 06:26:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:26:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:26:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zfyCSwyxNq/Render', 06:26:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:36 INFO - 'HOME': '/Users/cltbld', 06:26:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:36 INFO - 'LOGNAME': 'cltbld', 06:26:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:36 INFO - 'MOZ_NO_REMOTE': '1', 06:26:36 INFO - 'NO_EM_RESTART': '1', 06:26:36 INFO - 'PAGER': '/bin/cat', 06:26:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:36 INFO - 'PWD': '/builds/slave/test', 06:26:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:36 INFO - 'SHELL': '/bin/bash', 06:26:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H6Tw9W5ngG/Listeners', 06:26:36 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 06:26:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:36 INFO - 'USER': 'cltbld', 06:26:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:36 INFO - 'XPC_FLAGS': '0x0', 06:26:36 INFO - 'XPC_SERVICE_NAME': '0', 06:26:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:36 INFO - Downloading/unpacking jsonschema 06:26:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:26:38 INFO - Downloading/unpacking functools32 (from jsonschema) 06:26:38 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 06:26:38 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 06:26:38 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 06:26:38 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 06:26:38 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 06:26:38 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 06:26:39 INFO - Downloading functools32-3.2.3-2.tar.gz 06:26:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:26:39 INFO - warning: no files found matching '*.txt' 06:26:39 INFO - no previously-included directories found matching 'build' 06:26:39 INFO - no previously-included directories found matching 'dist' 06:26:39 INFO - no previously-included directories found matching '.git*' 06:26:39 INFO - Installing collected packages: jsonschema, functools32 06:26:39 INFO - Running setup.py install for functools32 06:26:39 INFO - warning: no files found matching '*.txt' 06:26:39 INFO - no previously-included directories found matching 'build' 06:26:39 INFO - no previously-included directories found matching 'dist' 06:26:39 INFO - no previously-included directories found matching '.git*' 06:26:39 INFO - Successfully installed jsonschema functools32 06:26:39 INFO - Cleaning up... 06:26:39 INFO - Return code: 0 06:26:39 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:26:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:26:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10294aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10295a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e42c94a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027e9e00>, '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 0x102652db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027e4b20>, '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.zfyCSwyxNq/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.H6Tw9W5ngG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 06:26:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 06:26:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 06:26:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zfyCSwyxNq/Render', 06:26:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:39 INFO - 'HOME': '/Users/cltbld', 06:26:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:39 INFO - 'LOGNAME': 'cltbld', 06:26:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:39 INFO - 'MOZ_NO_REMOTE': '1', 06:26:39 INFO - 'NO_EM_RESTART': '1', 06:26:39 INFO - 'PAGER': '/bin/cat', 06:26:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:39 INFO - 'PWD': '/builds/slave/test', 06:26:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:39 INFO - 'SHELL': '/bin/bash', 06:26:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H6Tw9W5ngG/Listeners', 06:26:39 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 06:26:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:39 INFO - 'USER': 'cltbld', 06:26:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:39 INFO - 'XPC_FLAGS': '0x0', 06:26:39 INFO - 'XPC_SERVICE_NAME': '0', 06:26:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:39 INFO - Downloading/unpacking flake8 06:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:42 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:26:42 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:42 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:26:42 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:26:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:26:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:26:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:26:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:26:43 INFO - no previously-included directories found matching 'docs/_build' 06:26:43 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:26:43 INFO - Running setup.py install for pep8 06:26:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:26:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:26:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:26:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:26:43 INFO - no previously-included directories found matching 'docs/_build' 06:26:43 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:26:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:26:43 INFO - Cleaning up... 06:26:43 INFO - Return code: 0 06:26:43 INFO - Running post-action listener: _resource_record_post_action 06:26:43 INFO - Running post-action listener: _start_resource_monitoring 06:26:43 INFO - Starting resource monitoring. 06:26:43 INFO - ##### 06:26:43 INFO - ##### Running install step. 06:26:43 INFO - ##### 06:26:43 INFO - Running pre-action listener: _resource_record_pre_action 06:26:43 INFO - Running main action method: install 06:26:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:26:43 INFO - Reading from file tmpfile_stdout 06:26:43 INFO - Detecting whether we're running mozinstall >=1.0... 06:26:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:26:44 INFO - Reading from file tmpfile_stdout 06:26:44 INFO - Output received: 06:26:44 INFO - Usage: mozinstall [options] installer 06:26:44 INFO - Options: 06:26:44 INFO - -h, --help show this help message and exit 06:26:44 INFO - -d DEST, --destination=DEST 06:26:44 INFO - Directory to install application into. [default: 06:26:44 INFO - "/builds/slave/test"] 06:26:44 INFO - --app=APP Application being installed. [default: firefox] 06:26:44 INFO - mkdir: /builds/slave/test/build/application 06:26:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 06:27:15 INFO - Reading from file tmpfile_stdout 06:27:15 INFO - Output received: 06:27:15 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 06:27:15 INFO - Running post-action listener: _resource_record_post_action 06:27:15 INFO - ##### 06:27:15 INFO - ##### Running run-tests step. 06:27:15 INFO - ##### 06:27:15 INFO - Running pre-action listener: _resource_record_pre_action 06:27:15 INFO - Running main action method: run_tests 06:27:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:27:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:27:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:27:15 INFO - 2016-05-26 06:27:15.642 screenresolution[1641:7421] starting screenresolution argv=screenresolution get 06:27:15 INFO - 2016-05-26 06:27:15.645 screenresolution[1641:7421] Display 0: 1600x1200x32@60 06:27:15 INFO - 2016-05-26 06:27:15.650 screenresolution[1642:7423] starting screenresolution argv=screenresolution list 06:27:15 INFO - Available Modes on Display 0 06:27:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:27:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:27:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:27:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:27:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:27:15 INFO - Intel Iris: 06:27:15 INFO - Chipset Model: Intel Iris 06:27:15 INFO - Type: GPU 06:27:15 INFO - Bus: Built-In 06:27:15 INFO - VRAM (Dynamic, Max): 1536 MB 06:27:15 INFO - Vendor: Intel (0x8086) 06:27:15 INFO - Device ID: 0x0a2e 06:27:15 INFO - Revision ID: 0x0009 06:27:15 INFO - Displays: 06:27:15 INFO - Display: 06:27:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:27:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:27:15 INFO - Display Serial Number: 1600x1200 60 06:27:15 INFO - Main Display: Yes 06:27:15 INFO - Mirror: Off 06:27:15 INFO - Online: Yes 06:27:15 INFO - Rotation: Supported 06:27:15 INFO - Return code: 0 06:27:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:27:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:27:15 INFO - Python 2.7.3 06:27:15 INFO - Return code: 0 06:27:15 INFO - grabbing minidump binary from tooltool 06:27: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')]} 06:27:15 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 0x1027e9e00>, '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 0x102652db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027e4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:27:15 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 06:27:15 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 06:27:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:27:15 INFO - Return code: 0 06:27:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:27:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:27:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:27:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:27:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:27:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:27:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:27:17 INFO - Return code: 0 06:27:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0244', '--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 06:27:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0244 --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 06:27:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zfyCSwyxNq/Render', 06:27:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:17 INFO - 'HOME': '/Users/cltbld', 06:27:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:17 INFO - 'LOGNAME': 'cltbld', 06:27:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:27:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:27:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:17 INFO - 'MOZ_NO_REMOTE': '1', 06:27:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:27:17 INFO - 'NO_EM_RESTART': '1', 06:27:17 INFO - 'PAGER': '/bin/cat', 06:27:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:17 INFO - 'PWD': '/builds/slave/test', 06:27:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:27:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:17 INFO - 'SHELL': '/bin/bash', 06:27:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H6Tw9W5ngG/Listeners', 06:27:17 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 06:27:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:17 INFO - 'USER': 'cltbld', 06:27:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:17 INFO - 'XPC_FLAGS': '0x0', 06:27:17 INFO - 'XPC_SERVICE_NAME': '0', 06:27:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0244', '--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 06:27:17 INFO - mozversion application_buildid: 20160526052739 06:27:17 INFO - mozversion application_changeset: e6b03d71815222297fdf701695ddd2cc743b0a80 06:27:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:27:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:27:17 INFO - mozversion application_name: Firefox 06:27:17 INFO - mozversion application_remotingname: firefox-dev 06:27:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:27:17 INFO - mozversion application_vendor: Mozilla 06:27:17 INFO - mozversion application_version: 48.0a2 06:27:17 INFO - mozversion platform_buildid: 20160526052739 06:27:17 INFO - mozversion platform_changeset: e6b03d71815222297fdf701695ddd2cc743b0a80 06:27:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:27:17 INFO - mozversion platform_version: 48.0a2 06:27:17 INFO - using testdate: 1464269237 06:27:17 INFO - actual date: 1464269237 06:27:17 INFO - starting webserver on 'localhost:49275' 06:27:17 INFO - SUITE-START | Running 2 tests 06:27:17 INFO - TEST-START | dromaeo_css 06:27:17 INFO - operating with platform_type : mac_ 06:27:17 INFO - Initialising browser for dromaeo_css test... 06:27:17 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmppYIX9o/profile http://localhost:49275/getInfo.html) 06:27:20 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 06:27:20 INFO - PROCESS | 1660 | colorDepth:24 06:27:20 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 06:27:20 INFO - PROCESS | 1660 | __metrics 06:27:20 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 06:27:20 INFO - TEST-INFO | 1660: exit 0 06:27:20 INFO - Browser initialized. 06:27:20 INFO - Running cycle 1/1 for dromaeo_css test... 06:27:20 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmppYIX9o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:27:32 INFO - PROCESS | 1663 | RSS: Main: 221044736 06:27:32 INFO - PROCESS | 1663 | 06:28:22 INFO - PROCESS | 1663 | 1464269302377 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] 06:30:32 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 06:30:34 INFO - PROCESS | 1663 | RSS: Main: 231813120 06:30:34 INFO - PROCESS | 1663 | 06:33:36 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 06:33:36 INFO - PROCESS | 1663 | RSS: Main: 243605504 06:33:36 INFO - PROCESS | 1663 | 06:36:38 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 06:36:39 INFO - PROCESS | 1663 | RSS: Main: 245399552 06:36:39 INFO - PROCESS | 1663 | 06:39:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 06:39:41 INFO - PROCESS | 1663 | RSS: Main: 247218176 06:39:41 INFO - PROCESS | 1663 | 06:42:44 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 06:42:44 INFO - PROCESS | 1663 | RSS: Main: 250175488 06:42:44 INFO - PROCESS | 1663 | 06:45:46 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 06:45:47 INFO - PROCESS | 1663 | RSS: Main: 250695680 06:45:47 INFO - PROCESS | 1663 | 06:45:47 INFO - PROCESS | 1663 | __start_tp_report 06:45:47 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 06:45:47 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 06:45:47 INFO - PROCESS | 1663 | |i|pagename|runs| 06:45:47 INFO - PROCESS | 1663 | |0;dojo.html;2912.6650435199945;3082.830444325562;3495.3358861816255;3500.859965601376;3524.5241892344525;4455.398521199637;5536.723163841807;5711.742971566279;5734.942650573493;5739.684317362543;4649.30260460931;5717.542596592262;5729.684867332306;5771.682557459349;5784.334801497829;5258.316418865561;5283.841484755463;5314.282571852808;5317.308749862513;5343.812966546172;5169.6898186108765;5330.493603107699;5363.115086010804;5401.756920938567;5415.891682166354;5242.764075616587;5282.5245727884485;5326.440723724011;5330.706811125387;5342.439043900393;5299.443558426367;5314.548263397635;5339.919901201485;5351.143816989302;5366.463353664602;5087.211482220262;5211.088059589594;5239.449857764918;5304.787808487656;5327.7336133193185;5595.076812325961;5608.803691870765;5671.6880571568545;5687.374388817227;5688.772449102031;6245.56281060326;6252.249730032425;6277.591956522811;6317.336679648617;6336.303006669263;6130.367562715103;6240.906386404207;6320.778772742978;6343.429091381798;6392.1370614966845;5836.649801011953;5869.8239052828485;5883.911741323884;5899.203607513016;5919.822405327847;6306.558540902184;6329.082283068929;6343.809685709436;6347.079673447324;6359.682015899187;6073.089036644538;6153.323134455206;6201.844953876189;6210.813675589739;6272.6550039747335;6292.276388215322;6311.211098612674;6314.368563143741;6320.241571011508;6328.145700330397;6141.201643786369;6251.0623406488485;6289.905651415141;6297.4962003039645;6308.27454842699;6119.533019689859;6135.754569817157;6275.748970041239;6301.558890877594;6303.716332765037;4857.0528746894015;4907.975460122677;4907.993861258434;4918.0901533216465;4938.999999999928;4429.512753597101;4431.933520997188;4444.444444444444;4453.087117141044;4476.37330773692;5661.518770904437;5738.053221218535;5755.424457554295;5802.941970580325;5813.505852002627;5148.6910785353;6244.75020999164;6303.464205542488;6333.936660633427;6364.395382438661;5785.797497087582;5841.474267315877;5857.092150716646;5858.209141765892;5921.40785921413;5308.893822123536;5413.133898576288;5437.83686489398;5449.427810079964;5468.617196796186;5241.89516209666;5385.488378604026;6408.61548307103;6409.615423074631;6445.033245013192;6247.6563788990225;6294.653794041372;6295.527835412271;6307.873842523216;6328.588641738456;6239.220097487814;6351.777687780906;6375.362463753588;6414.390632889868;6432.388923052303;6202.658853763086;6227.159333490102;6242.687865606611;6243.687815609111;6261.655608941552;6197.411245931809;6280.9685951569945;6305.684715764285;6308.842278943063;6314.336994615499;5735.483806457439;5981.252343457068;6096.634201947897;6109.786157484645;6152;6124.663143527148;6133.371907999364;6171.567990240819;6173.660448675366;6178.443940045418;4773.307870358848;5875.823725288248;6015.4586087252355;6035.366286540026;6077.270727512727;6025.728842202024;6117.204763380732;6142.0383852415835;6185.072239164161;6191.102290167811;5877.764889404376;6014.609050411709;6014.686447330532;6014.977453832947;6049.758009679564 06:45:47 INFO - PROCESS | 1663 | |1;ext.html;490.87728067983005;505.41624423790523;569.4789267819945;586.478034549251;648.7738174849535;37958.81020594897;38867;38995.610043899585;39005;39007.80496097523;40110.598894011055;40364.596354036454;40600.984975375555;40677;40832.183356332855;9414.01152878948;9488.193503552171;9534.904650953506;9572.32993690442;9583.089606487372;9461.290403219786;9577.808443831154;9692.563834627423;9711.271654625896;9742.561584728668;9592.616295348178;9633.421994680306;9685.22518198546;9695.127438530531;9705.708828735149;9569.043095690446;9593.808123837554;9625.711228663116;9690.903090969106;9713.368631038995;8705;9594.568244429018;9648.614055437843;9716.708498745049;9744.853827192597;35275.29449411023;36522;39816;39818;39972.80013599913;3364.680355366236;3372.477266023746;3446.1556918555116;3588.94126232762;3756.8309426075893;27845.86077069622;28099.719002810118;28711.856440717664;28754.568681469795;29072.982445614514;2777.7361150690654;2782.9721702783118;2804.467151241258;2817.2534278416238;2823.957640635392;390199;459903.9999999967;460258;460587;461988;63101.684491577245;63102.368976310565;63565.68217158977;63765.68117159477;63943;66236;69282.9607555887;70658.2934170662;70915.64542177255;70953.64523177351;17067.48797536076;17155.57111072208;17248.482545523653;17295.135243237786;17300.22149003298;12776.361181940865;12788.808167877489;13109.803352949522;13214;13226.404811783494;20016.399508014492;20177.59644807125;20467.8976605116;20474.897625512076;20514;6811.386975172259;7985.441019128605;8130.268275855083;8135.959320203361;8183.795405114801;937.9577918993662;1034.5086084110137;1082.357376349846;1111.7331840358418;1159.605734050427;30139.945101921327;30163.396732065237;30171.09486715402;30418.54372184419;30742.538861917088;1136.431784107946;1140.3955903371227;1174.9706257343635;1199.6341115959542;1260.8360913081424;15016.109255002839;15365.692686146438;15372.846271537364;15540.222988850512;15629.68740625181;636152;640097;644824;645786;650268.7486562537;58814;60930.39069609336;61000.389996100355;61041.38958610446;61190.69404652948;1487.9628009299638;1493.7908692783237;1503.7669161280019;1509.3283488847578;1577.7002369549748;29831.254218644706;29986.25034374158;30018.69981300159;30454;30608.540871886493;53476.4652353487;53595.6601084976;54139;54189.729051356066;54217.45782542124;450089;451554;456392;457142;457560;27016.729832702207;27287.863560682068;27329.453410932067;27437.451250975268;27818;46825;46981.59055228349;47039.52960470488;48141;48636.5136348632;37996;38686;38806.80596596999;39021;39159.41260881032;38202.670047141735;38326;38417.61582384141;38455;39282.214355713295;35079.94760157199;38164.23671526498;38538.421923670605;38649;39319.2136157281;27423.45153096967;31840.407979600463;32706.461157709255;32886;32944.505832412055;24839.50320993606;24845.503089938462;24934;24956.37609059788;25192.622110667988 06:45:47 INFO - PROCESS | 1663 | |2;jquery.html;320.32411611499737;395.63601486632285;397.4753325610194;409.4124930724409;412.6348181859058;16125.83874161261;17950.281988720453;19453.513662158453;20400.775953442804;21708.457288567795;15258.389664413438;20353.796462035374;20374.796252037475;21329;21360.252391166312;5959.88080238395;6028.939710602882;6124.081387791823;6180.536459765513;6214.65819379934;5152.458991805863;5166.741662916858;6035.094735789645;6093.146959425684;6143.170671959296;5807.970960145193;5990.281166260055;6002.369751176129;6016.398360163992;6118.969405152968;5987.042073268256;5992.2809262888295;6053.092036194562;6092.29938557065;6112.174856394375;5376.166694162411;5479.123340265538;5726.169705392695;5758.452946970073;5769.221155144086;13482.988775841852;13595;13692.246926419042;13727.725445491034;13791.586252412444;2078.9376318710465;2127.8723276603455;2195.7365116186;2499.0128899084943;2563.7564431378987;9754.804903901882;9760.90239097607;10115.747106322327;10268.229882758824;13216.20702757828;1606.5742578216782;1657.7430498272788;1764.0826770079616;1800.3788692900928;1815.0652414006665;106101;108231;115766;116151.41924290408;117581;9789.657361992297;11935.866092721177;11994.520219191309;12054.638360849189;12322.260664360167;13260.801087983687;14391.208483533297;15075.321610527333;15282.923585382225;15286.923565382102;7259.564426134449;8735.388522803343;8858.335624828163;8993.370464067584;9022.819543609092;8992.235659968845;8999.77500562478;9031.412958157698;9032.412893161923;9060.365774395728;3822.7515211511163;4541.091781643684;4638.489766125724;4659.813607455664;4679.110968915895;1686.7349487884087;1768.9292428302724;1927.681932481125;2000.7999200080167;2020;878.4773060029272;1004.5630150884242;1011.049613363436;1020.2042406922612;1044.2011860926398;17165.400554204072;20212.09045592952;21058.368248952556;21236.36290911275;21330.89334553317;960.351762560269;970.1559643110398;978.7846673731767;978.9378524301071;1004.9045340692622;10854.837177442345;11243.227046867685;11248.807436600635;11252.66242012741;11303.434828258554;232784.83607581854;232960;498039;620214;626550;99728.00271997188;121837;123762.14356784655;123780.38109809393;123900;993.4089216916068;1002.3534820041033;1007.2143727892255;1028.804527139841;1029.4749677664295;17951.820481795537;18021.909890450464;18042.097895105453;18163;18755.624887502447;43025.3546196801;49252.507474924794;49869.00261994812;50268.994620108126;53806.461935380146;73176;73877.38578946459;74852;75633.62183189268;75677.62161189159;8215.465994710325;8252.33981281508;8323.375746818892;8334.08325084228;8377.95811020941;11641.6507504775;12720.491180352683;12962.935185324013;13116.278604676452;13248.933755331162;9098.272138229058;11103;11261.831072534082;11646.95177434035;11705.531778728755;10096.444695541815;10101.646442374482;10647.733806654896;10976.615818446317;11198.44007799623;10449.477526123816;11143.052840508324;11509.712257193638;11624.244424112405;11808;8205.343572514304;8307.127751585997;8401.03388110375;8534.359923005924;8650.740477785677;8720.520371379382;10039.799204015733;10408.687739367831;10421.478926053818;10454.268201225841 06:45:47 INFO - PROCESS | 1663 | |3;mootools.html;1569.7645353197022;1594.346318009616;1840.1719226348146;1946.7371904792867;1949.1131535151499;7933.563653999027;8530.40287179896;8720.694775682852;8893.066228046051;8927.19655231029;8607.655693772242;8645.913540864589;8734.956325218365;8740.825183496327;8776.868346974801;2014.979850201494;2020.3635854705722;2057.2079749296518;2062.113291284747;2066.8243199328;2031.3905828251538;2404.1224952892244;2416.2871952773976;2451.82837201396;2464.581021226387;2454.447749256421;2455.5088982203542;2461.360046387943;2467.90128394864;2478.4423504711476;2442.9755702442926;2445.425325048612;2454.828162028659;2457.9631305530343;2466.519028789369;2415;2468.8148388870904;2476.616124500705;2477.3930387055107;2487.962680559793;8937.106289371075;8969.147930946614;8970.820583588356;8975.192232699022;8992.910070899272;5367.624266301361;5435.320584926884;5513.61404701671;5515.7242137893345;5519.089350257203;3456.723462123024;3486.860525578974;3496.4755286706945;3555.8577656893694;3556.395412779834;1034.2966782587837;1047.287844265899;1048.423367148073;1053.968380948565;1057.2704833664748;11842.171048026641;11966.282023078644;12039.578614748529;12143.514259429612;12149.574764883188;4808.213658589974;4813.663043586986;4826.396700412449;4896.877578060506;4926.113299606033;10167.745806354753;10198.286119971532;10217.029382208679;10289.382637041801;10403.739906502398;5338.279332290169;5367.35591728995;5456.017916775018;5491.835244942658;5514.172874068921;4407.338899165086;4801.4958429364415;4918.458969513351;4982.950170498249;4987.925181122286;4092.8976775580254;4101.815418306183;4138.0689344897155;4170.937435938464;4209.873703788891;1345.6501309659363;1346.0510340210128;1348.4336578636996;1351.4391527516168;1354.288998275913;938.9999650372332;949.1694767078806;954.7613096725819;955.9235261179228;985.142925654685;5940.287165540163;5955.225820643289;6063.090536419484;6122.908156377658;6162.907556386658;441.911617676466;454.59768105226453;457.7551010209589;458.63309352517854;461.6214703942802;4021.3766866135797;4042.595740425934;4115.444415003996;4137.296659567821;4154.148399578079;10632.521536530874;10829.67510974672;10933.671989840317;10934.890651093387;10959.0684791792;10356.844647330296;10597;10670.51982660782;10764.46177691128;10792.730181745519;8228.21831925978;8291.33669306454;8314;8355.373346998878;8407.285380742705;5786.508146807569;5948.226730525177;5968.4926781224085;6142.692865356804;6220.844478887883;9111;9166.17504424605;9252.121048500381;9253.814923701622;9484.24126069899;9327.393719408488;9686.273529485175;9706.466144362128;9789.559469823733;9791.363561368487;6803.829904252433;7324.560526368435;7407.1852096268285;7547.50941188821;7554.206808285051;5939.782344619256;8314;8319.625616847135;8334.749787531919;8401.747947561584;6723.966380168068;7418.66616002292;7441.181470038183;7492.063492063492;7597.696092156473;7295.306945840137;7295.781126566211;7338.192798792235;7346.926530734838;7373.225811289952;7121.9643901780155;7127.964360178166;7166.570005799668;7184.820379490555;7207.963960180376;4969.602431805519;4976.875578110431;4980.601551875769;5019.472955339636;5039.21892106724;5023.547880690755;5029.29589857413;5048.091343558048;5112.309838171948;5257.526822585985 06:45:47 INFO - PROCESS | 1663 | |4;prototype.html;325.95110733389987;326.92153883068056;331.6368576408833;331.65010913486265;335.6458935822706;5336.4129945705945;5840.678762668052;6616.470682345413;6842.418394436472;6854;4935.506449355072;5050.545450909417;6591.780520603692;6625.304343043983;6822.795316140511;4758.714477131374;4871.001444703845;4975.253711943201;4981.5018498150075;4984.202527595586;3732.64539868712;4928.964917367357;4994.77523511441;5007.4491805901325;5009.824656137036;4975.626827987898;5006.74966251686;5118.6416950813455;5142.717150556718;5156.484351564833;5037.3451451311275;5057.216131499624;5066.214736715815;5080.441151473335;5080.847574572768;4224.880406692229;4277.358396240557;4286.485621725382;4298.505671847746;4309.35359696045;5350.491703288181;6311.936880631227;6532.118164047888;6533.7713180038945;6593.439557637558;5923.644581325134;5969.283685957713;5986.820395388146;5989.431004054608;5998.730057147395;7257.564546127249;7286.234945330717;7287.198408175096;7290;7305.707771689126;4752.762361881927;4777.211760059586;4788.569028787391;4790.736509491977;4808.8797780055775;7392.371648409838;7712.190220026872;7731.497452665615;7734.922650773477;7749;4876.634252431082;4892.363992680928;4897.338859254026;4912.189488734319;4918.163912134945;4920.6309526785635;4959.305697202394;4968.6025117990475;4986.0277245936695;4993.1262029144755;5953.553483488756;5956.791512297049;5966.612170208922;5973.104034394789;5975.74509353032;5854.824355269348;7322.743703970335;7338.192798792129;7343.412526997828;7359.668814903235;1505.6762795998914;1654.9007059576222;1743.2852530462449;1745.938892138769;1748.9912550437166;1086.603389762731;1125.834761022338;1129.3675541696675;1131.3595286834122;1139.2822521811372;6760.18877734675;6797.762078327334;6831.351021652935;6831.658417079127;6837.692303846339;7303.59830209333;7592.544447333178;7594.392448604099;7606.011218541555;7606.3914886808925;6471.764117941029;6515.381038801306;6666.566673166229;6779.220389655155;6779.864402711918;3988.700847436454;3990.92245093823;3995.06116062725;4024.335984562573;4045.4538637284177;8057.838843223103;8070.112287648354;8075.596220188967;8075.757727268191;8097.190280971856;7596.734114305973;7833.33416659579;7901.525908445512;7908.051033875971;7929.801754956057;8012.83974320522;8056.180336882045;8068.152843951418;8069.596520173968;8085.393595480245;7577.734779282699;7600.125985511737;7639.923600764144;7641.503302285334;7652.80867978305;7476.289752473506;7478.401727861649;7505;7508.2867127622785;7535.472516923982;7829.1779363168325;8050.798730031562;8131.227533384319;8151.959240203761;8154.633041513028;3507.2634746702456;4275.914481710411;4719.339292498984;4719.622430205507;4773.188557945088;5686.317641882835;7572.901929220342;7678.692852285846;7682.308592226726;7683.615819208905;7388.815279617837;7390.9260907390235;7394.371478424179;7401.555906645403;7425.034745483053;7145.035420218204;7358.742444014648;7371.6314184289495;7389.187189409053;7397.371223445852;7119.89641605552;7174.713011479483;7216.819579510344;7231.349178573953;7268.418526517971;5940.435658612425;6105.938940610537;7364.484486085923;7421.480496365202;7423.480356375002;7020.974485709878;7170.103737032871;7230.6384680766805;7247.782566523013;7251 06:45:47 INFO - PROCESS | 1663 | |5;yui.html;676.1885737115462;697.8534507753374;699.1435491522885;731.3856360657047;765.9808504787376;12432.751344973096;15111.92444037781;15246.847531524681;15278.694426111471;15285.541433756976;13760.58718238452;13811.033227674066;13834.100783449094;13864.376103075361;15166;900.3922352412096;901.4411065139623;912.762681702757;913.0732306708663;914.5975770660921;898.3890954151175;908.600215905003;916.8945571259324;919.3105171121658;919.9512555686523;974.2936371130944;977.3207620703614;982.921366290695;983.7147227304073;986.8322385194498;953.7663272498213;975.2588033094828;975.8585005174245;981.8036392721449;982.3614650477247;697.2469732688684;700.4956431369457;708.6952610377535;715.7172916697875;731.7877815433518;15147.924260378737;15154.939154259206;15180.848191518055;15201.31594078258;15204.08775473464;12338.691532711664;12340.13619046667;12454.875451245553;12488.625341239778;12676.302803345812;8212;8621.267192288664;9478.810423791554;10187.796244075076;10275.948620256851;3470.9132271693156;3473.1664400543686;3488.6162522122545;3543.6456354364614;3710.2023065040876;60354.887578934526;79725;81210.59394703047;81979;84226.57886710588;9208.079192080739;9313.580888859882;9345.210344109404;9474.715758527254;9748.9025109748;20411.795882041286;20623.381298561068;20626.484337891674;20682.276120336017;20693.68959465609;7318.560886346836;7410.925890741024;7431.8142046449275;7435.925640743523;7499.1001079869675;4055.209234199303;4063.79681015948;4142.544320124784;4214.726042807208;4233.809478573472;3455.7235421166747;3460;3460.8615655374006;3499.4225952718025;3528.7177025837873;634.7207228819304;657.2737125476375;663.8008597420754;675.0953722012526;678.3894494954482;513.9412809612211;542.6391449685915;576.7174084698468;586.214472606709;599.2599140062019;7944.960275198587;7956.482828616122;7986.920130798734;8014.358851291882;8306.916930830614;492.450917227289;497.3286063813895;504.508104598014;590.4597293476421;590.9349971503132;3691.206390626028;3872.773006682867;3930.4890364253038;3964.920701585952;4013.2976729072298;89896;90651;91589;92033;92379.0762092384;70903.93644095343;72854;72984.90522642164;75701.24298757053;77145.61427192828;67726.66136669285;69220.3077969224;69647.65176174087;70188.59622807516;70192.64903675551;7603.125640551408;7679.654415551202;7776.377889768692;7812.173913043478;7866.819977003495;45913.31130032986;46978.12087516461;47290.76354618205;47935.28097078477;49058;63398.68300658467;64157.679211603645;64568;64724;65120.69758604896;10765.192610554082;10958.506867191156;11108.166887483309;11132.554697811996;11597.304161750322;11678.941605291919;11842.822357664814;11970;11974.68278489348;12213.75572488563;11272.210945233755;11362.14783891195;11423.714407139889;11604.593839215924;11617.76764464689;11240.044596209415;11243.55025798959;11303.095752339957;11363.204575679623;11512.136589755635;10856.185786065918;10956;11146.275492092987;11352.035077018545;11444.542218311508;9560.235181185628;9630.229581633592;9653.082957119062;9773.706788796348;9955.651552195639;7084.397826184832;8202.712905048294;9212.631494740135;9559.61761529531;9983.400995940267 06:45:47 INFO - PROCESS | 1663 | __end_tp_report 06:45:47 INFO - PROCESS | 1663 | __start_cc_report 06:45:47 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,417 06:45:47 INFO - PROCESS | 1663 | __end_cc_report 06:45:47 INFO - PROCESS | 1663 | __startTimestamp1464270347124__endTimestamp 06:45:47 INFO - PROCESS | 1663 | 06:45:47 INFO - PROCESS | 1663 | ------- Summary: start ------- 06:45:47 INFO - PROCESS | 1663 | Number of tests: 6 06:45:47 INFO - PROCESS | 1663 | 06:45:47 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5753.47 Median:5981.25 stddev:670.80 (11.2%) stddev-sans-first:634.76 06:45:47 INFO - PROCESS | 1663 | Values: 2912.7 3082.8 3495.3 3500.9 3524.5 4455.4 5536.7 5711.7 5734.9 5739.7 4649.3 5717.5 5729.7 5771.7 5784.3 5258.3 5283.8 5314.3 5317.3 5343.8 5169.7 5330.5 5363.1 5401.8 5415.9 5242.8 5282.5 5326.4 5330.7 5342.4 5299.4 5314.5 5339.9 5351.1 5366.5 5087.2 5211.1 5239.4 5304.8 5327.7 5595.1 5608.8 5671.7 5687.4 5688.8 6245.6 6252.2 6277.6 6317.3 6336.3 6130.4 6240.9 6320.8 6343.4 6392.1 5836.6 5869.8 5883.9 5899.2 5919.8 6306.6 6329.1 6343.8 6347.1 6359.7 6073.1 6153.3 6201.8 6210.8 6272.7 6292.3 6311.2 6314.4 6320.2 6328.1 6141.2 6251.1 6289.9 6297.5 6308.3 6119.5 6135.8 6275.7 6301.6 6303.7 4857.1 4908.0 4908.0 4918.1 4939.0 4429.5 4431.9 4444.4 4453.1 4476.4 5661.5 5738.1 5755.4 5802.9 5813.5 5148.7 6244.8 6303.5 6333.9 6364.4 5785.8 5841.5 5857.1 5858.2 5921.4 5308.9 5413.1 5437.8 5449.4 5468.6 5241.9 5385.5 6408.6 6409.6 6445.0 6247.7 6294.7 6295.5 6307.9 6328.6 6239.2 6351.8 6375.4 6414.4 6432.4 6202.7 6227.2 6242.7 6243.7 6261.7 6197.4 6281.0 6305.7 6308.8 6314.3 5735.5 5981.3 6096.6 6109.8 6152.0 6124.7 6133.4 6171.6 6173.7 6178.4 4773.3 5875.8 6015.5 6035.4 6077.3 6025.7 6117.2 6142.0 6185.1 6191.1 5877.8 6014.6 6014.7 6015.0 6049.8 06:45:47 INFO - PROCESS | 1663 | 06:45:47 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:66048.86 Median:39006.40 stddev:139559.51 (357.8%) stddev-sans-first:139864.21 06:45:47 INFO - PROCESS | 1663 | Values: 490.9 505.4 569.5 586.5 648.8 37958.8 38867.0 38995.6 39005.0 39007.8 40110.6 40364.6 40601.0 40677.0 40832.2 9414.0 9488.2 9534.9 9572.3 9583.1 9461.3 9577.8 9692.6 9711.3 9742.6 9592.6 9633.4 9685.2 9695.1 9705.7 9569.0 9593.8 9625.7 9690.9 9713.4 8705.0 9594.6 9648.6 9716.7 9744.9 35275.3 36522.0 39816.0 39818.0 39972.8 3364.7 3372.5 3446.2 3588.9 3756.8 27845.9 28099.7 28711.9 28754.6 29073.0 2777.7 2783.0 2804.5 2817.3 2824.0 390199.0 459904.0 460258.0 460587.0 461988.0 63101.7 63102.4 63565.7 63765.7 63943.0 66236.0 69283.0 70658.3 70915.6 70953.6 17067.5 17155.6 17248.5 17295.1 17300.2 12776.4 12788.8 13109.8 13214.0 13226.4 20016.4 20177.6 20467.9 20474.9 20514.0 6811.4 7985.4 8130.3 8136.0 8183.8 938.0 1034.5 1082.4 1111.7 1159.6 30139.9 30163.4 30171.1 30418.5 30742.5 1136.4 1140.4 1175.0 1199.6 1260.8 15016.1 15365.7 15372.8 15540.2 15629.7 636152.0 640097.0 644824.0 645786.0 650268.7 58814.0 60930.4 61000.4 61041.4 61190.7 1488.0 1493.8 1503.8 1509.3 1577.7 29831.3 29986.3 30018.7 30454.0 30608.5 53476.5 53595.7 54139.0 54189.7 54217.5 450089.0 451554.0 456392.0 457142.0 457560.0 27016.7 27287.9 27329.5 27437.5 27818.0 46825.0 46981.6 47039.5 48141.0 48636.5 37996.0 38686.0 38806.8 39021.0 39159.4 38202.7 38326.0 38417.6 38455.0 39282.2 35079.9 38164.2 38538.4 38649.0 39319.2 27423.5 31840.4 32706.5 32886.0 32944.5 24839.5 24845.5 24934.0 24956.4 25192.6 06:45:47 INFO - PROCESS | 1663 | 06:45:47 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:29708.65 Median:11728.44 stddev:80724.56 (688.3%) stddev-sans-first:80920.85 06:45:47 INFO - PROCESS | 1663 | Values: 320.3 395.6 397.5 409.4 412.6 16125.8 17950.3 19453.5 20400.8 21708.5 15258.4 20353.8 20374.8 21329.0 21360.3 5959.9 6028.9 6124.1 6180.5 6214.7 5152.5 5166.7 6035.1 6093.1 6143.2 5808.0 5990.3 6002.4 6016.4 6119.0 5987.0 5992.3 6053.1 6092.3 6112.2 5376.2 5479.1 5726.2 5758.5 5769.2 13483.0 13595.0 13692.2 13727.7 13791.6 2078.9 2127.9 2195.7 2499.0 2563.8 9754.8 9760.9 10115.7 10268.2 13216.2 1606.6 1657.7 1764.1 1800.4 1815.1 106101.0 108231.0 115766.0 116151.4 117581.0 9789.7 11935.9 11994.5 12054.6 12322.3 13260.8 14391.2 15075.3 15282.9 15286.9 7259.6 8735.4 8858.3 8993.4 9022.8 8992.2 8999.8 9031.4 9032.4 9060.4 3822.8 4541.1 4638.5 4659.8 4679.1 1686.7 1768.9 1927.7 2000.8 2020.0 878.5 1004.6 1011.0 1020.2 1044.2 17165.4 20212.1 21058.4 21236.4 21330.9 960.4 970.2 978.8 978.9 1004.9 10854.8 11243.2 11248.8 11252.7 11303.4 232784.8 232960.0 498039.0 620214.0 626550.0 99728.0 121837.0 123762.1 123780.4 123900.0 993.4 1002.4 1007.2 1028.8 1029.5 17951.8 18021.9 18042.1 18163.0 18755.6 43025.4 49252.5 49869.0 50269.0 53806.5 73176.0 73877.4 74852.0 75633.6 75677.6 8215.5 8252.3 8323.4 8334.1 8378.0 11641.7 12720.5 12962.9 13116.3 13248.9 9098.3 11103.0 11261.8 11647.0 11705.5 10096.4 10101.6 10647.7 10976.6 11198.4 10449.5 11143.1 11509.7 11624.2 11808.0 8205.3 8307.1 8401.0 8534.4 8650.7 8720.5 10039.8 10408.7 10421.5 10454.3 06:45:47 INFO - PROCESS | 1663 | 06:45:47 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5726.27 Median:4981.78 stddev:3196.52 (64.2%) stddev-sans-first:3190.22 06:45:47 INFO - PROCESS | 1663 | Values: 1569.8 1594.3 1840.2 1946.7 1949.1 7933.6 8530.4 8720.7 8893.1 8927.2 8607.7 8645.9 8735.0 8740.8 8776.9 2015.0 2020.4 2057.2 2062.1 2066.8 2031.4 2404.1 2416.3 2451.8 2464.6 2454.4 2455.5 2461.4 2467.9 2478.4 2443.0 2445.4 2454.8 2458.0 2466.5 2415.0 2468.8 2476.6 2477.4 2488.0 8937.1 8969.1 8970.8 8975.2 8992.9 5367.6 5435.3 5513.6 5515.7 5519.1 3456.7 3486.9 3496.5 3555.9 3556.4 1034.3 1047.3 1048.4 1054.0 1057.3 11842.2 11966.3 12039.6 12143.5 12149.6 4808.2 4813.7 4826.4 4896.9 4926.1 10167.7 10198.3 10217.0 10289.4 10403.7 5338.3 5367.4 5456.0 5491.8 5514.2 4407.3 4801.5 4918.5 4983.0 4987.9 4092.9 4101.8 4138.1 4170.9 4209.9 1345.7 1346.1 1348.4 1351.4 1354.3 939.0 949.2 954.8 955.9 985.1 5940.3 5955.2 6063.1 6122.9 6162.9 441.9 454.6 457.8 458.6 461.6 4021.4 4042.6 4115.4 4137.3 4154.1 10632.5 10829.7 10933.7 10934.9 10959.1 10356.8 10597.0 10670.5 10764.5 10792.7 8228.2 8291.3 8314.0 8355.4 8407.3 5786.5 5948.2 5968.5 6142.7 6220.8 9111.0 9166.2 9252.1 9253.8 9484.2 9327.4 9686.3 9706.5 9789.6 9791.4 6803.8 7324.6 7407.2 7547.5 7554.2 5939.8 8314.0 8319.6 8334.7 8401.7 6724.0 7418.7 7441.2 7492.1 7597.7 7295.3 7295.8 7338.2 7346.9 7373.2 7122.0 7128.0 7166.6 7184.8 7208.0 4969.6 4976.9 4980.6 5019.5 5039.2 5023.5 5029.3 5048.1 5112.3 5257.5 06:45:47 INFO - PROCESS | 1663 | 06:45:47 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:5948.36 Median:6604.96 stddev:1939.31 (29.4%) stddev-sans-first:1898.28 06:45:47 INFO - PROCESS | 1663 | Values: 326.0 326.9 331.6 331.7 335.6 5336.4 5840.7 6616.5 6842.4 6854.0 4935.5 5050.5 6591.8 6625.3 6822.8 4758.7 4871.0 4975.3 4981.5 4984.2 3732.6 4929.0 4994.8 5007.4 5009.8 4975.6 5006.7 5118.6 5142.7 5156.5 5037.3 5057.2 5066.2 5080.4 5080.8 4224.9 4277.4 4286.5 4298.5 4309.4 5350.5 6311.9 6532.1 6533.8 6593.4 5923.6 5969.3 5986.8 5989.4 5998.7 7257.6 7286.2 7287.2 7290.0 7305.7 4752.8 4777.2 4788.6 4790.7 4808.9 7392.4 7712.2 7731.5 7734.9 7749.0 4876.6 4892.4 4897.3 4912.2 4918.2 4920.6 4959.3 4968.6 4986.0 4993.1 5953.6 5956.8 5966.6 5973.1 5975.7 5854.8 7322.7 7338.2 7343.4 7359.7 1505.7 1654.9 1743.3 1745.9 1749.0 1086.6 1125.8 1129.4 1131.4 1139.3 6760.2 6797.8 6831.4 6831.7 6837.7 7303.6 7592.5 7594.4 7606.0 7606.4 6471.8 6515.4 6666.6 6779.2 6779.9 3988.7 3990.9 3995.1 4024.3 4045.5 8057.8 8070.1 8075.6 8075.8 8097.2 7596.7 7833.3 7901.5 7908.1 7929.8 8012.8 8056.2 8068.2 8069.6 8085.4 7577.7 7600.1 7639.9 7641.5 7652.8 7476.3 7478.4 7505.0 7508.3 7535.5 7829.2 8050.8 8131.2 8152.0 8154.6 3507.3 4275.9 4719.3 4719.6 4773.2 5686.3 7572.9 7678.7 7682.3 7683.6 7388.8 7390.9 7394.4 7401.6 7425.0 7145.0 7358.7 7371.6 7389.2 7397.4 7119.9 7174.7 7216.8 7231.3 7268.4 5940.4 6105.9 7364.5 7421.5 7423.5 7021.0 7170.1 7230.6 7247.8 7251.0 06:45:47 INFO - PROCESS | 1663 | 06:45:47 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:17790.66 Median:64362.84 stddev:24889.26 (38.7%) stddev-sans-first:24925.91 06:45:47 INFO - PROCESS | 1663 | Values: 676.2 697.9 699.1 731.4 766.0 12432.8 15111.9 15246.8 15278.7 15285.5 13760.6 13811.0 13834.1 13864.4 15166.0 900.4 901.4 912.8 913.1 914.6 898.4 908.6 916.9 919.3 920.0 974.3 977.3 982.9 983.7 986.8 953.8 975.3 975.9 981.8 982.4 697.2 700.5 708.7 715.7 731.8 15147.9 15154.9 15180.8 15201.3 15204.1 12338.7 12340.1 12454.9 12488.6 12676.3 8212.0 8621.3 9478.8 10187.8 10275.9 3470.9 3473.2 3488.6 3543.6 3710.2 60354.9 79725.0 81210.6 81979.0 84226.6 9208.1 9313.6 9345.2 9474.7 9748.9 20411.8 20623.4 20626.5 20682.3 20693.7 7318.6 7410.9 7431.8 7435.9 7499.1 4055.2 4063.8 4142.5 4214.7 4233.8 3455.7 3460.0 3460.9 3499.4 3528.7 634.7 657.3 663.8 675.1 678.4 513.9 542.6 576.7 586.2 599.3 7945.0 7956.5 7986.9 8014.4 8306.9 492.5 497.3 504.5 590.5 590.9 3691.2 3872.8 3930.5 3964.9 4013.3 89896.0 90651.0 91589.0 92033.0 92379.1 70903.9 72854.0 72984.9 75701.2 77145.6 67726.7 69220.3 69647.7 70188.6 70192.6 7603.1 7679.7 7776.4 7812.2 7866.8 45913.3 46978.1 47290.8 47935.3 49058.0 63398.7 64157.7 64568.0 64724.0 65120.7 10765.2 10958.5 11108.2 11132.6 11597.3 11678.9 11842.8 11970.0 11974.7 12213.8 11272.2 11362.1 11423.7 11604.6 11617.8 11240.0 11243.6 11303.1 11363.2 11512.1 10856.2 10956.0 11146.3 11352.0 11444.5 9560.2 9630.2 9653.1 9773.7 9955.7 7084.4 8202.7 9212.6 9559.6 9983.4 06:45:47 INFO - PROCESS | 1663 | -------- Summary: end -------- 06:45:47 INFO - PROCESS | 1663 | 06:45:47 INFO - TEST-INFO | 1663: exit 0 06:45:47 INFO - TEST-OK | dromaeo_css | took 1110331ms 06:45:47 INFO - TEST-START | kraken 06:45:47 INFO - operating with platform_type : mac_ 06:45:47 INFO - Initialising browser for kraken test... 06:45:47 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpAFOlpJ/profile http://localhost:49275/getInfo.html) 06:45:50 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 06:45:50 INFO - PROCESS | 1675 | colorDepth:24 06:45:50 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 06:45:50 INFO - PROCESS | 1675 | __metrics 06:45:50 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 06:45:50 INFO - TEST-INFO | 1675: exit 0 06:45:50 INFO - Browser initialized. 06:45:50 INFO - Running cycle 1/1 for kraken test... 06:45:50 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpAFOlpJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:46:02 INFO - PROCESS | 1678 | RSS: Main: 220917760 06:46:02 INFO - PROCESS | 1678 | 06:46:05 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 06:46:06 INFO - PROCESS | 1678 | RSS: Main: 232902656 06:46:06 INFO - PROCESS | 1678 | 06:46:09 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 06:46:09 INFO - PROCESS | 1678 | RSS: Main: 241893376 06:46:09 INFO - PROCESS | 1678 | 06:46:13 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 06:46:13 INFO - PROCESS | 1678 | RSS: Main: 245452800 06:46:13 INFO - PROCESS | 1678 | 06:46:15 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 06:46:16 INFO - PROCESS | 1678 | RSS: Main: 245870592 06:46:16 INFO - PROCESS | 1678 | 06:46:18 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:46:19 INFO - PROCESS | 1678 | RSS: Main: 246579200 06:46:19 INFO - PROCESS | 1678 | 06:46:22 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 06:46:22 INFO - PROCESS | 1678 | RSS: Main: 246493184 06:46:22 INFO - PROCESS | 1678 | 06:46:25 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 06:46:26 INFO - PROCESS | 1678 | RSS: Main: 247865344 06:46:26 INFO - PROCESS | 1678 | 06:46:29 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 06:46:30 INFO - PROCESS | 1678 | RSS: Main: 248057856 06:46:30 INFO - PROCESS | 1678 | 06:46:31 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:46:32 INFO - PROCESS | 1678 | RSS: Main: 247414784 06:46:32 INFO - PROCESS | 1678 | 06:46:34 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:46:34 INFO - PROCESS | 1678 | RSS: Main: 247496704 06:46:34 INFO - PROCESS | 1678 | 06:46:36 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:46:37 INFO - PROCESS | 1678 | RSS: Main: 247738368 06:46:37 INFO - PROCESS | 1678 | 06:46:40 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:46:40 INFO - PROCESS | 1678 | RSS: Main: 248504320 06:46:40 INFO - PROCESS | 1678 | 06:46:43 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:46:44 INFO - PROCESS | 1678 | RSS: Main: 247701504 06:46:44 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 06:46:46 INFO - PROCESS | 1678 | RSS: Main: 249593856 06:46:46 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | __start_tp_report 06:46:46 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 06:46:46 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 06:46:46 INFO - PROCESS | 1678 | |i|pagename|runs| 06:46:46 INFO - PROCESS | 1678 | |0;ai-astar;118;175;122;116;119;118;106;116;118;118 06:46:46 INFO - PROCESS | 1678 | |1;audio-beat-detection;152;165;216;192;133;136;175;122;193;114 06:46:46 INFO - PROCESS | 1678 | |2;audio-dft;198;197;199;200;196;227;274;204;196;194 06:46:46 INFO - PROCESS | 1678 | |3;audio-fft;67;115;105;141;74;49;75;56;131;46 06:46:46 INFO - PROCESS | 1678 | |4;audio-oscillator;79;81;124;79;83;86;116;81;79;123 06:46:46 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;93;75;134;131;91;96;136;88;93;89 06:46:46 INFO - PROCESS | 1678 | |6;imaging-darkroom;95;97;95;97;94;97;96;132;104;153 06:46:46 INFO - PROCESS | 1678 | |7;imaging-desaturate;75;153;148;89;90;91;93;90;91;91 06:46:46 INFO - PROCESS | 1678 | |8;json-parse-financial;62;65;66;66;65;67;66;84;63;62 06:46:46 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;66;68;68;70;78;75;69;67;67;70 06:46:46 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;81;84;82;84;82;84;81;82;85;85 06:46:46 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;134;122;111;144;152;139;151;127;149;145 06:46:46 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;172;166;166;167;174;177;170;167;174;166 06:46:46 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;71;71;69;69;66;68;66;69;68;66 06:46:46 INFO - PROCESS | 1678 | __end_tp_report 06:46:46 INFO - PROCESS | 1678 | __start_cc_report 06:46:46 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1102 06:46:46 INFO - PROCESS | 1678 | __end_cc_report 06:46:46 INFO - PROCESS | 1678 | __startTimestamp1464270406322__endTimestamp 06:46:46 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | ------- Summary: start ------- 06:46:46 INFO - PROCESS | 1678 | Number of tests: 14 06:46:46 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:122.60 Median:118.00 stddev:18.88 (16.0%) stddev-sans-first:19.95 06:46:46 INFO - PROCESS | 1678 | Values: 118.0 175.0 122.0 116.0 119.0 118.0 106.0 116.0 118.0 118.0 06:46:46 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:159.80 Median:170.00 stddev:34.04 (20.0%) stddev-sans-first:35.99 06:46:46 INFO - PROCESS | 1678 | Values: 152.0 165.0 216.0 192.0 133.0 136.0 175.0 122.0 193.0 114.0 06:46:46 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:208.50 Median:199.50 stddev:24.90 (12.5%) stddev-sans-first:26.12 06:46:46 INFO - PROCESS | 1678 | Values: 198.0 197.0 199.0 200.0 196.0 227.0 274.0 204.0 196.0 194.0 06:46:46 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:85.90 Median:52.50 stddev:34.56 (65.8%) stddev-sans-first:35.97 06:46:46 INFO - PROCESS | 1678 | Values: 67.0 115.0 105.0 141.0 74.0 49.0 75.0 56.0 131.0 46.0 06:46:46 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:80.00 stddev:19.48 (24.3%) stddev-sans-first:19.98 06:46:46 INFO - PROCESS | 1678 | Values: 79.0 81.0 124.0 79.0 83.0 86.0 116.0 81.0 79.0 123.0 06:46:46 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:102.60 Median:90.00 stddev:22.19 (24.7%) stddev-sans-first:23.26 06:46:46 INFO - PROCESS | 1678 | Values: 93.0 75.0 134.0 131.0 91.0 96.0 136.0 88.0 93.0 89.0 06:46:46 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:106.00 Median:95.50 stddev:20.05 (21.0%) stddev-sans-first:20.87 06:46:46 INFO - PROCESS | 1678 | Values: 95.0 97.0 95.0 97.0 94.0 97.0 96.0 132.0 104.0 153.0 06:46:46 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:101.10 Median:90.50 stddev:26.54 (29.3%) stddev-sans-first:26.41 06:46:46 INFO - PROCESS | 1678 | Values: 75.0 153.0 148.0 89.0 90.0 91.0 93.0 90.0 91.0 91.0 06:46:46 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:66.60 Median:66.00 stddev:6.36 (9.6%) stddev-sans-first:6.53 06:46:46 INFO - PROCESS | 1678 | Values: 62.0 65.0 66.0 66.0 65.0 67.0 66.0 84.0 63.0 62.0 06:46:46 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:69.50 stddev:3.82 (5.5%) stddev-sans-first:3.80 06:46:46 INFO - PROCESS | 1678 | Values: 66.0 68.0 68.0 70.0 78.0 75.0 69.0 67.0 67.0 70.0 06:46:46 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:84.00 stddev:1.56 (1.9%) stddev-sans-first:1.48 06:46:46 INFO - PROCESS | 1678 | Values: 81.0 84.0 82.0 84.0 82.0 84.0 81.0 82.0 85.0 85.0 06:46:46 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:137.40 Median:144.50 stddev:13.70 (9.5%) stddev-sans-first:14.48 06:46:46 INFO - PROCESS | 1678 | Values: 134.0 122.0 111.0 144.0 152.0 139.0 151.0 127.0 149.0 145.0 06:46:46 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.90 Median:171.00 stddev:4.09 (2.4%) stddev-sans-first:4.27 06:46:46 INFO - PROCESS | 1678 | Values: 172.0 166.0 166.0 167.0 174.0 177.0 170.0 167.0 174.0 166.0 06:46:46 INFO - PROCESS | 1678 | 06:46:46 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:1.89 (2.7%) stddev-sans-first:1.73 06:46:46 INFO - PROCESS | 1678 | Values: 71.0 71.0 69.0 69.0 66.0 68.0 66.0 69.0 68.0 66.0 06:46:46 INFO - PROCESS | 1678 | -------- Summary: end -------- 06:46:46 INFO - PROCESS | 1678 | 06:46:47 INFO - TEST-INFO | 1678: exit 0 06:46:47 INFO - TEST-OK | kraken | took 59454ms 06:46:47 INFO - SUITE-END | took 1169s 06:46:47 INFO - Completed test suite (00:19:30) 06:46:47 INFO - javascript benchmark 06:46:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2912.6650435199945, 3082.830444325562, 3495.3358861816255, 3500.859965601376, 3524.5241892344525, 4455.398521199637, 5536.723163841807, 5711.742971566279, 5734.942650573493, 5739.684317362543, 4649.30260460931, 5717.542596592262, 5729.684867332306, 5771.682557459349, 5784.334801497829, 5258.316418865561, 5283.841484755463, 5314.282571852808, 5317.308749862513, 5343.812966546172, 5169.6898186108765, 5330.493603107699, 5363.115086010804, 5401.756920938567, 5415.891682166354, 5242.764075616587, 5282.5245727884485, 5326.440723724011, 5330.706811125387, 5342.439043900393, 5299.443558426367, 5314.548263397635, 5339.919901201485, 5351.143816989302, 5366.463353664602, 5087.211482220262, 5211.088059589594, 5239.449857764918, 5304.787808487656, 5327.7336133193185, 5595.076812325961, 5608.803691870765, 5671.6880571568545, 5687.374388817227, 5688.772449102031, 6245.56281060326, 6252.249730032425, 6277.591956522811, 6317.336679648617, 6336.303006669263, 6130.367562715103, 6240.906386404207, 6320.778772742978, 6343.429091381798, 6392.1370614966845, 5836.649801011953, 5869.8239052828485, 5883.911741323884, 5899.203607513016, 5919.822405327847, 6306.558540902184, 6329.082283068929, 6343.809685709436, 6347.079673447324, 6359.682015899187, 6073.089036644538, 6153.323134455206, 6201.844953876189, 6210.813675589739, 6272.6550039747335, 6292.276388215322, 6311.211098612674, 6314.368563143741, 6320.241571011508, 6328.145700330397, 6141.201643786369, 6251.0623406488485, 6289.905651415141, 6297.4962003039645, 6308.27454842699, 6119.533019689859, 6135.754569817157, 6275.748970041239, 6301.558890877594, 6303.716332765037, 4857.0528746894015, 4907.975460122677, 4907.993861258434, 4918.0901533216465, 4938.999999999928, 4429.512753597101, 4431.933520997188, 4444.444444444444, 4453.087117141044, 4476.37330773692, 5661.518770904437, 5738.053221218535, 5755.424457554295, 5802.941970580325, 5813.505852002627, 5148.6910785353, 6244.75020999164, 6303.464205542488, 6333.936660633427, 6364.395382438661, 5785.797497087582, 5841.474267315877, 5857.092150716646, 5858.209141765892, 5921.40785921413, 5308.893822123536, 5413.133898576288, 5437.83686489398, 5449.427810079964, 5468.617196796186, 5241.89516209666, 5385.488378604026, 6408.61548307103, 6409.615423074631, 6445.033245013192, 6247.6563788990225, 6294.653794041372, 6295.527835412271, 6307.873842523216, 6328.588641738456, 6239.220097487814, 6351.777687780906, 6375.362463753588, 6414.390632889868, 6432.388923052303, 6202.658853763086, 6227.159333490102, 6242.687865606611, 6243.687815609111, 6261.655608941552, 6197.411245931809, 6280.9685951569945, 6305.684715764285, 6308.842278943063, 6314.336994615499, 5735.483806457439, 5981.252343457068, 6096.634201947897, 6109.786157484645, 6152.0, 6124.663143527148, 6133.371907999364, 6171.567990240819, 6173.660448675366, 6178.443940045418, 4773.307870358848, 5875.823725288248, 6015.4586087252355, 6035.366286540026, 6077.270727512727, 6025.728842202024, 6117.204763380732, 6142.0383852415835, 6185.072239164161, 6191.102290167811, 5877.764889404376, 6014.609050411709, 6014.686447330532, 6014.977453832947, 6049.758009679564], "value": 5710.978187632804, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.87728067983005, 505.41624423790523, 569.4789267819945, 586.478034549251, 648.7738174849535, 37958.81020594897, 38867.0, 38995.610043899585, 39005.0, 39007.80496097523, 40110.598894011055, 40364.596354036454, 40600.984975375555, 40677.0, 40832.183356332855, 9414.01152878948, 9488.193503552171, 9534.904650953506, 9572.32993690442, 9583.089606487372, 9461.290403219786, 9577.808443831154, 9692.563834627423, 9711.271654625896, 9742.561584728668, 9592.616295348178, 9633.421994680306, 9685.22518198546, 9695.127438530531, 9705.708828735149, 9569.043095690446, 9593.808123837554, 9625.711228663116, 9690.903090969106, 9713.368631038995, 8705.0, 9594.568244429018, 9648.614055437843, 9716.708498745049, 9744.853827192597, 35275.29449411023, 36522.0, 39816.0, 39818.0, 39972.80013599913, 3364.680355366236, 3372.477266023746, 3446.1556918555116, 3588.94126232762, 3756.8309426075893, 27845.86077069622, 28099.719002810118, 28711.856440717664, 28754.568681469795, 29072.982445614514, 2777.7361150690654, 2782.9721702783118, 2804.467151241258, 2817.2534278416238, 2823.957640635392, 390199.0, 459903.9999999967, 460258.0, 460587.0, 461988.0, 63101.684491577245, 63102.368976310565, 63565.68217158977, 63765.68117159477, 63943.0, 66236.0, 69282.9607555887, 70658.2934170662, 70915.64542177255, 70953.64523177351, 17067.48797536076, 17155.57111072208, 17248.482545523653, 17295.135243237786, 17300.22149003298, 12776.361181940865, 12788.808167877489, 13109.803352949522, 13214.0, 13226.404811783494, 20016.399508014492, 20177.59644807125, 20467.8976605116, 20474.897625512076, 20514.0, 6811.386975172259, 7985.441019128605, 8130.268275855083, 8135.959320203361, 8183.795405114801, 937.9577918993662, 1034.5086084110137, 1082.357376349846, 1111.7331840358418, 1159.605734050427, 30139.945101921327, 30163.396732065237, 30171.09486715402, 30418.54372184419, 30742.538861917088, 1136.431784107946, 1140.3955903371227, 1174.9706257343635, 1199.6341115959542, 1260.8360913081424, 15016.109255002839, 15365.692686146438, 15372.846271537364, 15540.222988850512, 15629.68740625181, 636152.0, 640097.0, 644824.0, 645786.0, 650268.7486562537, 58814.0, 60930.39069609336, 61000.389996100355, 61041.38958610446, 61190.69404652948, 1487.9628009299638, 1493.7908692783237, 1503.7669161280019, 1509.3283488847578, 1577.7002369549748, 29831.254218644706, 29986.25034374158, 30018.69981300159, 30454.0, 30608.540871886493, 53476.4652353487, 53595.6601084976, 54139.0, 54189.729051356066, 54217.45782542124, 450089.0, 451554.0, 456392.0, 457142.0, 457560.0, 27016.729832702207, 27287.863560682068, 27329.453410932067, 27437.451250975268, 27818.0, 46825.0, 46981.59055228349, 47039.52960470488, 48141.0, 48636.5136348632, 37996.0, 38686.0, 38806.80596596999, 39021.0, 39159.41260881032, 38202.670047141735, 38326.0, 38417.61582384141, 38455.0, 39282.214355713295, 35079.94760157199, 38164.23671526498, 38538.421923670605, 38649.0, 39319.2136157281, 27423.45153096967, 31840.407979600463, 32706.461157709255, 32886.0, 32944.505832412055, 24839.50320993606, 24845.503089938462, 24934.0, 24956.37609059788, 25192.622110667988], "value": 20089.50112091053, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.32411611499737, 395.63601486632285, 397.4753325610194, 409.4124930724409, 412.6348181859058, 16125.83874161261, 17950.281988720453, 19453.513662158453, 20400.775953442804, 21708.457288567795, 15258.389664413438, 20353.796462035374, 20374.796252037475, 21329.0, 21360.252391166312, 5959.88080238395, 6028.939710602882, 6124.081387791823, 6180.536459765513, 6214.65819379934, 5152.458991805863, 5166.741662916858, 6035.094735789645, 6093.146959425684, 6143.170671959296, 5807.970960145193, 5990.281166260055, 6002.369751176129, 6016.398360163992, 6118.969405152968, 5987.042073268256, 5992.2809262888295, 6053.092036194562, 6092.29938557065, 6112.174856394375, 5376.166694162411, 5479.123340265538, 5726.169705392695, 5758.452946970073, 5769.221155144086, 13482.988775841852, 13595.0, 13692.246926419042, 13727.725445491034, 13791.586252412444, 2078.9376318710465, 2127.8723276603455, 2195.7365116186, 2499.0128899084943, 2563.7564431378987, 9754.804903901882, 9760.90239097607, 10115.747106322327, 10268.229882758824, 13216.20702757828, 1606.5742578216782, 1657.7430498272788, 1764.0826770079616, 1800.3788692900928, 1815.0652414006665, 106101.0, 108231.0, 115766.0, 116151.41924290408, 117581.0, 9789.657361992297, 11935.866092721177, 11994.520219191309, 12054.638360849189, 12322.260664360167, 13260.801087983687, 14391.208483533297, 15075.321610527333, 15282.923585382225, 15286.923565382102, 7259.564426134449, 8735.388522803343, 8858.335624828163, 8993.370464067584, 9022.819543609092, 8992.235659968845, 8999.77500562478, 9031.412958157698, 9032.412893161923, 9060.365774395728, 3822.7515211511163, 4541.091781643684, 4638.489766125724, 4659.813607455664, 4679.110968915895, 1686.7349487884087, 1768.9292428302724, 1927.681932481125, 2000.7999200080167, 2020.0, 878.4773060029272, 1004.5630150884242, 1011.049613363436, 1020.2042406922612, 1044.2011860926398, 17165.400554204072, 20212.09045592952, 21058.368248952556, 21236.36290911275, 21330.89334553317, 960.351762560269, 970.1559643110398, 978.7846673731767, 978.9378524301071, 1004.9045340692622, 10854.837177442345, 11243.227046867685, 11248.807436600635, 11252.66242012741, 11303.434828258554, 232784.83607581854, 232960.0, 498039.0, 620214.0, 626550.0, 99728.00271997188, 121837.0, 123762.14356784655, 123780.38109809393, 123900.0, 993.4089216916068, 1002.3534820041033, 1007.2143727892255, 1028.804527139841, 1029.4749677664295, 17951.820481795537, 18021.909890450464, 18042.097895105453, 18163.0, 18755.624887502447, 43025.3546196801, 49252.507474924794, 49869.00261994812, 50268.994620108126, 53806.461935380146, 73176.0, 73877.38578946459, 74852.0, 75633.62183189268, 75677.62161189159, 8215.465994710325, 8252.33981281508, 8323.375746818892, 8334.08325084228, 8377.95811020941, 11641.6507504775, 12720.491180352683, 12962.935185324013, 13116.278604676452, 13248.933755331162, 9098.272138229058, 11103.0, 11261.831072534082, 11646.95177434035, 11705.531778728755, 10096.444695541815, 10101.646442374482, 10647.733806654896, 10976.615818446317, 11198.44007799623, 10449.477526123816, 11143.052840508324, 11509.712257193638, 11624.244424112405, 11808.0, 8205.343572514304, 8307.127751585997, 8401.03388110375, 8534.359923005924, 8650.740477785677, 8720.520371379382, 10039.799204015733, 10408.687739367831, 10421.478926053818, 10454.268201225841], "value": 9281.624291272801, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1569.7645353197022, 1594.346318009616, 1840.1719226348146, 1946.7371904792867, 1949.1131535151499, 7933.563653999027, 8530.40287179896, 8720.694775682852, 8893.066228046051, 8927.19655231029, 8607.655693772242, 8645.913540864589, 8734.956325218365, 8740.825183496327, 8776.868346974801, 2014.979850201494, 2020.3635854705722, 2057.2079749296518, 2062.113291284747, 2066.8243199328, 2031.3905828251538, 2404.1224952892244, 2416.2871952773976, 2451.82837201396, 2464.581021226387, 2454.447749256421, 2455.5088982203542, 2461.360046387943, 2467.90128394864, 2478.4423504711476, 2442.9755702442926, 2445.425325048612, 2454.828162028659, 2457.9631305530343, 2466.519028789369, 2415.0, 2468.8148388870904, 2476.616124500705, 2477.3930387055107, 2487.962680559793, 8937.106289371075, 8969.147930946614, 8970.820583588356, 8975.192232699022, 8992.910070899272, 5367.624266301361, 5435.320584926884, 5513.61404701671, 5515.7242137893345, 5519.089350257203, 3456.723462123024, 3486.860525578974, 3496.4755286706945, 3555.8577656893694, 3556.395412779834, 1034.2966782587837, 1047.287844265899, 1048.423367148073, 1053.968380948565, 1057.2704833664748, 11842.171048026641, 11966.282023078644, 12039.578614748529, 12143.514259429612, 12149.574764883188, 4808.213658589974, 4813.663043586986, 4826.396700412449, 4896.877578060506, 4926.113299606033, 10167.745806354753, 10198.286119971532, 10217.029382208679, 10289.382637041801, 10403.739906502398, 5338.279332290169, 5367.35591728995, 5456.017916775018, 5491.835244942658, 5514.172874068921, 4407.338899165086, 4801.4958429364415, 4918.458969513351, 4982.950170498249, 4987.925181122286, 4092.8976775580254, 4101.815418306183, 4138.0689344897155, 4170.937435938464, 4209.873703788891, 1345.6501309659363, 1346.0510340210128, 1348.4336578636996, 1351.4391527516168, 1354.288998275913, 938.9999650372332, 949.1694767078806, 954.7613096725819, 955.9235261179228, 985.142925654685, 5940.287165540163, 5955.225820643289, 6063.090536419484, 6122.908156377658, 6162.907556386658, 441.911617676466, 454.59768105226453, 457.7551010209589, 458.63309352517854, 461.6214703942802, 4021.3766866135797, 4042.595740425934, 4115.444415003996, 4137.296659567821, 4154.148399578079, 10632.521536530874, 10829.67510974672, 10933.671989840317, 10934.890651093387, 10959.0684791792, 10356.844647330296, 10597.0, 10670.51982660782, 10764.46177691128, 10792.730181745519, 8228.21831925978, 8291.33669306454, 8314.0, 8355.373346998878, 8407.285380742705, 5786.508146807569, 5948.226730525177, 5968.4926781224085, 6142.692865356804, 6220.844478887883, 9111.0, 9166.17504424605, 9252.121048500381, 9253.814923701622, 9484.24126069899, 9327.393719408488, 9686.273529485175, 9706.466144362128, 9789.559469823733, 9791.363561368487, 6803.829904252433, 7324.560526368435, 7407.1852096268285, 7547.50941188821, 7554.206808285051, 5939.782344619256, 8314.0, 8319.625616847135, 8334.749787531919, 8401.747947561584, 6723.966380168068, 7418.66616002292, 7441.181470038183, 7492.063492063492, 7597.696092156473, 7295.306945840137, 7295.781126566211, 7338.192798792235, 7346.926530734838, 7373.225811289952, 7121.9643901780155, 7127.964360178166, 7166.570005799668, 7184.820379490555, 7207.963960180376, 4969.602431805519, 4976.875578110431, 4980.601551875769, 5019.472955339636, 5039.21892106724, 5023.547880690755, 5029.29589857413, 5048.091343558048, 5112.309838171948, 5257.526822585985], "value": 4547.07311292985, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.95110733389987, 326.92153883068056, 331.6368576408833, 331.65010913486265, 335.6458935822706, 5336.4129945705945, 5840.678762668052, 6616.470682345413, 6842.418394436472, 6854.0, 4935.506449355072, 5050.545450909417, 6591.780520603692, 6625.304343043983, 6822.795316140511, 4758.714477131374, 4871.001444703845, 4975.253711943201, 4981.5018498150075, 4984.202527595586, 3732.64539868712, 4928.964917367357, 4994.77523511441, 5007.4491805901325, 5009.824656137036, 4975.626827987898, 5006.74966251686, 5118.6416950813455, 5142.717150556718, 5156.484351564833, 5037.3451451311275, 5057.216131499624, 5066.214736715815, 5080.441151473335, 5080.847574572768, 4224.880406692229, 4277.358396240557, 4286.485621725382, 4298.505671847746, 4309.35359696045, 5350.491703288181, 6311.936880631227, 6532.118164047888, 6533.7713180038945, 6593.439557637558, 5923.644581325134, 5969.283685957713, 5986.820395388146, 5989.431004054608, 5998.730057147395, 7257.564546127249, 7286.234945330717, 7287.198408175096, 7290.0, 7305.707771689126, 4752.762361881927, 4777.211760059586, 4788.569028787391, 4790.736509491977, 4808.8797780055775, 7392.371648409838, 7712.190220026872, 7731.497452665615, 7734.922650773477, 7749.0, 4876.634252431082, 4892.363992680928, 4897.338859254026, 4912.189488734319, 4918.163912134945, 4920.6309526785635, 4959.305697202394, 4968.6025117990475, 4986.0277245936695, 4993.1262029144755, 5953.553483488756, 5956.791512297049, 5966.612170208922, 5973.104034394789, 5975.74509353032, 5854.824355269348, 7322.743703970335, 7338.192798792129, 7343.412526997828, 7359.668814903235, 1505.6762795998914, 1654.9007059576222, 1743.2852530462449, 1745.938892138769, 1748.9912550437166, 1086.603389762731, 1125.834761022338, 1129.3675541696675, 1131.3595286834122, 1139.2822521811372, 6760.18877734675, 6797.762078327334, 6831.351021652935, 6831.658417079127, 6837.692303846339, 7303.59830209333, 7592.544447333178, 7594.392448604099, 7606.011218541555, 7606.3914886808925, 6471.764117941029, 6515.381038801306, 6666.566673166229, 6779.220389655155, 6779.864402711918, 3988.700847436454, 3990.92245093823, 3995.06116062725, 4024.335984562573, 4045.4538637284177, 8057.838843223103, 8070.112287648354, 8075.596220188967, 8075.757727268191, 8097.190280971856, 7596.734114305973, 7833.33416659579, 7901.525908445512, 7908.051033875971, 7929.801754956057, 8012.83974320522, 8056.180336882045, 8068.152843951418, 8069.596520173968, 8085.393595480245, 7577.734779282699, 7600.125985511737, 7639.923600764144, 7641.503302285334, 7652.80867978305, 7476.289752473506, 7478.401727861649, 7505.0, 7508.2867127622785, 7535.472516923982, 7829.1779363168325, 8050.798730031562, 8131.227533384319, 8151.959240203761, 8154.633041513028, 3507.2634746702456, 4275.914481710411, 4719.339292498984, 4719.622430205507, 4773.188557945088, 5686.317641882835, 7572.901929220342, 7678.692852285846, 7682.308592226726, 7683.615819208905, 7388.815279617837, 7390.9260907390235, 7394.371478424179, 7401.555906645403, 7425.034745483053, 7145.035420218204, 7358.742444014648, 7371.6314184289495, 7389.187189409053, 7397.371223445852, 7119.89641605552, 7174.713011479483, 7216.819579510344, 7231.349178573953, 7268.418526517971, 5940.435658612425, 6105.938940610537, 7364.484486085923, 7421.480496365202, 7423.480356375002, 7020.974485709878, 7170.103737032871, 7230.6384680766805, 7247.782566523013, 7251.0], "value": 5304.391388093621, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.1885737115462, 697.8534507753374, 699.1435491522885, 731.3856360657047, 765.9808504787376, 12432.751344973096, 15111.92444037781, 15246.847531524681, 15278.694426111471, 15285.541433756976, 13760.58718238452, 13811.033227674066, 13834.100783449094, 13864.376103075361, 15166.0, 900.3922352412096, 901.4411065139623, 912.762681702757, 913.0732306708663, 914.5975770660921, 898.3890954151175, 908.600215905003, 916.8945571259324, 919.3105171121658, 919.9512555686523, 974.2936371130944, 977.3207620703614, 982.921366290695, 983.7147227304073, 986.8322385194498, 953.7663272498213, 975.2588033094828, 975.8585005174245, 981.8036392721449, 982.3614650477247, 697.2469732688684, 700.4956431369457, 708.6952610377535, 715.7172916697875, 731.7877815433518, 15147.924260378737, 15154.939154259206, 15180.848191518055, 15201.31594078258, 15204.08775473464, 12338.691532711664, 12340.13619046667, 12454.875451245553, 12488.625341239778, 12676.302803345812, 8212.0, 8621.267192288664, 9478.810423791554, 10187.796244075076, 10275.948620256851, 3470.9132271693156, 3473.1664400543686, 3488.6162522122545, 3543.6456354364614, 3710.2023065040876, 60354.887578934526, 79725.0, 81210.59394703047, 81979.0, 84226.57886710588, 9208.079192080739, 9313.580888859882, 9345.210344109404, 9474.715758527254, 9748.9025109748, 20411.795882041286, 20623.381298561068, 20626.484337891674, 20682.276120336017, 20693.68959465609, 7318.560886346836, 7410.925890741024, 7431.8142046449275, 7435.925640743523, 7499.1001079869675, 4055.209234199303, 4063.79681015948, 4142.544320124784, 4214.726042807208, 4233.809478573472, 3455.7235421166747, 3460.0, 3460.8615655374006, 3499.4225952718025, 3528.7177025837873, 634.7207228819304, 657.2737125476375, 663.8008597420754, 675.0953722012526, 678.3894494954482, 513.9412809612211, 542.6391449685915, 576.7174084698468, 586.214472606709, 599.2599140062019, 7944.960275198587, 7956.482828616122, 7986.920130798734, 8014.358851291882, 8306.916930830614, 492.450917227289, 497.3286063813895, 504.508104598014, 590.4597293476421, 590.9349971503132, 3691.206390626028, 3872.773006682867, 3930.4890364253038, 3964.920701585952, 4013.2976729072298, 89896.0, 90651.0, 91589.0, 92033.0, 92379.0762092384, 70903.93644095343, 72854.0, 72984.90522642164, 75701.24298757053, 77145.61427192828, 67726.66136669285, 69220.3077969224, 69647.65176174087, 70188.59622807516, 70192.64903675551, 7603.125640551408, 7679.654415551202, 7776.377889768692, 7812.173913043478, 7866.819977003495, 45913.31130032986, 46978.12087516461, 47290.76354618205, 47935.28097078477, 49058.0, 63398.68300658467, 64157.679211603645, 64568.0, 64724.0, 65120.69758604896, 10765.192610554082, 10958.506867191156, 11108.166887483309, 11132.554697811996, 11597.304161750322, 11678.941605291919, 11842.822357664814, 11970.0, 11974.68278489348, 12213.75572488563, 11272.210945233755, 11362.14783891195, 11423.714407139889, 11604.593839215924, 11617.76764464689, 11240.044596209415, 11243.55025798959, 11303.095752339957, 11363.204575679623, 11512.136589755635, 10856.185786065918, 10956.0, 11146.275492092987, 11352.035077018545, 11444.542218311508, 9560.235181185628, 9630.229581633592, 9653.082957119062, 9773.706788796348, 9955.651552195639, 7084.397826184832, 8202.712905048294, 9212.631494740135, 9559.61761529531, 9983.400995940267], "value": 6805.62259903295, "unit": "score"}], "name": "dromaeo_css", "value": 7477.608003771694, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 175.0, 122.0, 116.0, 119.0, 118.0, 106.0, 116.0, 118.0, 118.0], "value": 122.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 165.0, 216.0, 192.0, 133.0, 136.0, 175.0, 122.0, 193.0, 114.0], "value": 159.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 197.0, 199.0, 200.0, 196.0, 227.0, 274.0, 204.0, 196.0, 194.0], "value": 208.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 115.0, 105.0, 141.0, 74.0, 49.0, 75.0, 56.0, 131.0, 46.0], "value": 85.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 124.0, 79.0, 83.0, 86.0, 116.0, 81.0, 79.0, 123.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 75.0, 134.0, 131.0, 91.0, 96.0, 136.0, 88.0, 93.0, 89.0], "value": 102.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 95.0, 97.0, 94.0, 97.0, 96.0, 132.0, 104.0, 153.0], "value": 106.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 153.0, 148.0, 89.0, 90.0, 91.0, 93.0, 90.0, 91.0, 91.0], "value": 101.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 66.0, 66.0, 65.0, 67.0, 66.0, 84.0, 63.0, 62.0], "value": 66.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 68.0, 70.0, 78.0, 75.0, 69.0, 67.0, 67.0, 70.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 82.0, 84.0, 82.0, 84.0, 81.0, 82.0, 85.0, 85.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 122.0, 111.0, 144.0, 152.0, 139.0, 151.0, 127.0, 149.0, 145.0], "value": 137.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 166.0, 166.0, 167.0, 174.0, 177.0, 170.0, 167.0, 174.0, 166.0], "value": 169.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 69.0, 69.0, 66.0, 68.0, 66.0, 69.0, 68.0, 66.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1574.6000000000001, "alertThreshold": 2.0}]} 06:46:47 INFO - Return code: 0 06:46:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:46:47 ERROR - # TBPL SUCCESS # 06:46:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:46:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:46:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:46:47 INFO - 2016-05-26 06:46:47.658 screenresolution[1683:10335] starting screenresolution argv=screenresolution get 06:46:47 INFO - 2016-05-26 06:46:47.661 screenresolution[1683:10335] Display 0: 1600x1200x32@60 06:46:47 INFO - 2016-05-26 06:46:47.667 screenresolution[1684:10338] starting screenresolution argv=screenresolution list 06:46:47 INFO - Available Modes on Display 0 06:46:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:46:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:46:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:46:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:46:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:46:47 INFO - Intel Iris: 06:46:47 INFO - Chipset Model: Intel Iris 06:46:47 INFO - Type: GPU 06:46:47 INFO - Bus: Built-In 06:46:47 INFO - VRAM (Dynamic, Max): 1536 MB 06:46:47 INFO - Vendor: Intel (0x8086) 06:46:47 INFO - Device ID: 0x0a2e 06:46:47 INFO - Revision ID: 0x0009 06:46:47 INFO - Displays: 06:46:47 INFO - Display: 06:46:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:46:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:46:47 INFO - Display Serial Number: 1600x1200 60 06:46:47 INFO - Main Display: Yes 06:46:47 INFO - Mirror: Off 06:46:47 INFO - Online: Yes 06:46:47 INFO - Rotation: Supported 06:46:47 INFO - Return code: 0 06:46:47 INFO - Running post-action listener: _resource_record_post_action 06:46:47 INFO - Running post-run listener: _resource_record_post_run 06:46:48 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 36898304; Write bytes: 652219904; Read time: 238; Write time: 1842 06:46:48 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344844800; Write bytes: 344258560; Read time: 26610; Write time: 693 06:46:48 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 35943936; Write bytes: 295968256; Read time: 227; Write time: 1109 06:46:48 INFO - Running post-run listener: _upload_blobber_files 06:46:48 INFO - Blob upload gear active. 06:46:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:46:48 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 06:46:48 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:46:48 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:46:49 INFO - (blobuploader) - INFO - Open directory for files ... 06:46:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:46:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:46:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:46:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:46:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:46:50 INFO - (blobuploader) - INFO - Done attempting. 06:46:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:46:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:46:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:46:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:46:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:46:51 INFO - (blobuploader) - INFO - Done attempting. 06:46:51 INFO - (blobuploader) - INFO - Iteration through files over. 06:46:51 INFO - Return code: 0 06:46:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:46:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:46:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/63f5f512c23a1285ae68385d43e89f2006cd75088cb869da621b575274814adc429c6deb8d37d0e1de9cc8ee4d190caa752a1feb2f48ceb91e5916e6f43039d5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/925ab5fc5137e10013931119a2781979805e176128c252c8ea1c6eff5930088d6e447bee3a2a8dc4485828b4a9436cf2d0609719afd985100de8fd777901db55"} 06:46:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:46:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:46:51 INFO - Contents: 06:46:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/63f5f512c23a1285ae68385d43e89f2006cd75088cb869da621b575274814adc429c6deb8d37d0e1de9cc8ee4d190caa752a1feb2f48ceb91e5916e6f43039d5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/925ab5fc5137e10013931119a2781979805e176128c252c8ea1c6eff5930088d6e447bee3a2a8dc4485828b4a9436cf2d0609719afd985100de8fd777901db55"} 06:46:51 INFO - Running post-run listener: copy_logs_to_upload_dir 06:46:51 INFO - Copying logs to upload dir... 06:46:51 INFO - mkdir: /builds/slave/test/build/upload/logs 06:46:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.060734 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-05-26 06:46:51.653206) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 06:46:51.657005) ========= 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.zfyCSwyxNq/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.H6Tw9W5ngG/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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-Aurora/sha512/63f5f512c23a1285ae68385d43e89f2006cd75088cb869da621b575274814adc429c6deb8d37d0e1de9cc8ee4d190caa752a1feb2f48ceb91e5916e6f43039d5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/925ab5fc5137e10013931119a2781979805e176128c252c8ea1c6eff5930088d6e447bee3a2a8dc4485828b4a9436cf2d0609719afd985100de8fd777901db55"} build_url:https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010974 build_url: 'https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/63f5f512c23a1285ae68385d43e89f2006cd75088cb869da621b575274814adc429c6deb8d37d0e1de9cc8ee4d190caa752a1feb2f48ceb91e5916e6f43039d5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/925ab5fc5137e10013931119a2781979805e176128c252c8ea1c6eff5930088d6e447bee3a2a8dc4485828b4a9436cf2d0609719afd985100de8fd777901db55"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 06:46:51.687174) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 06:46:51.687504) ========= 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.zfyCSwyxNq/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.H6Tw9W5ngG/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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.005063 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 06:46:51.736602) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 06:46:51.736910) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 06:46:51.737270) ========= ========= Total master_lag: 0.19 =========