builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0052 starttime: 1464269225.62 results: success (0) buildid: 20160526052739 builduid: 5abc185371e04a09b9188384b544af66 revision: e6b03d71815222297fdf701695ddd2cc743b0a80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 06:27:05.622950) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 06:27:05.623386) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 06:27:05.623658) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 06:27:05.644357) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 06:27:05.644677) ========= 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.UMOdv6bOau/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.aotvLwAaYc/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:27:05-- 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% 8.06M=0.001s 2016-05-26 06:27:05 (8.06 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105694 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 06:27:05.769835) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 06:27:05.770133) ========= 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.UMOdv6bOau/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.aotvLwAaYc/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.019584 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 06:27:05.817928) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 06:27:05.818242) ========= 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.UMOdv6bOau/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.aotvLwAaYc/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:27:05,872 truncating revision to first 12 chars 2016-05-26 06:27:05,872 Setting DEBUG logging. 2016-05-26 06:27:05,872 attempt 1/10 2016-05-26 06:27:05,873 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:27:07,135 unpacking tar archive at: mozilla-aurora-e6b03d718152/testing/mozharness/ program finished with exit code 0 elapsedTime=1.527206 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 06:27:07.364595) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 06:27:07.364911) ========= script_repo_revision: e6b03d71815222297fdf701695ddd2cc743b0a80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 06:27:07.365284) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 06:27:07.365560) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 06:27:07.381103) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-26 06:27:07.381380) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.UMOdv6bOau/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.aotvLwAaYc/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:27:07 INFO - MultiFileLogger online at 20160526 06:27:07 in /builds/slave/test 06:27:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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:27:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:27:07 INFO - {'append_to_log': False, 06:27:07 INFO - 'base_work_dir': '/builds/slave/test', 06:27:07 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:27:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:27:07 INFO - 'branch': 'Mozilla-Aurora', 06:27:07 INFO - 'buildbot_json_path': 'buildprops.json', 06:27:07 INFO - 'config_files': ('talos/mac_config.py',), 06:27:07 INFO - 'default_actions': ('clobber', 06:27:07 INFO - 'read-buildbot-config', 06:27:07 INFO - 'download-and-extract', 06:27:07 INFO - 'populate-webroot', 06:27:07 INFO - 'create-virtualenv', 06:27:07 INFO - 'install', 06:27:07 INFO - 'run-tests'), 06:27:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:27:07 INFO - 'download_minidump_stackwalk': True, 06:27:07 INFO - 'download_symbols': 'ondemand', 06:27:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:27:07 INFO - 'tooltool.py': '/tools/tooltool.py', 06:27:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:27:07 INFO - '/tools/misc-python/virtualenv.py')}, 06:27:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:27:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:27:07 INFO - 'installer_path': 'installer.exe', 06:27:07 INFO - 'log_level': 'info', 06:27:07 INFO - 'log_name': 'talos', 06:27:07 INFO - 'log_to_console': True, 06:27:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:27:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:27:07 INFO - 'opt_config_files': (), 06:27:07 INFO - 'pip_index': False, 06:27:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:27:07 INFO - 'cmd': ('bash', 06:27:07 INFO - '-c', 06:27:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:27:07 INFO - 'enabled': True, 06:27:07 INFO - 'halt_on_failure': False, 06:27:07 INFO - 'name': 'check_screen_resolution'},), 06:27:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:27:07 INFO - 'cmd': ('bash', 06:27:07 INFO - '-c', 06:27:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:27:07 INFO - 'enabled': True, 06:27:07 INFO - 'halt_on_failure': False, 06:27:07 INFO - 'name': 'check_screen_resolution'},), 06:27:07 INFO - 'run_cmd_checks_enabled': True, 06:27:07 INFO - 'sps_profile': False, 06:27:07 INFO - 'sps_profile_interval': 0, 06:27:07 INFO - 'suite': 'dromaeojs', 06:27:07 INFO - 'system_bits': '32', 06:27:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:27:07 INFO - 'title': 't-yosemite-r7-0052', 06:27:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:27:07 INFO - 'use_talos_json': True, 06:27:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:27:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:27:07 INFO - 'work_dir': 'build'} 06:27:07 INFO - ##### 06:27:07 INFO - ##### Running clobber step. 06:27:07 INFO - ##### 06:27:07 INFO - Running pre-action listener: _resource_record_pre_action 06:27:07 INFO - Running main action method: clobber 06:27:07 INFO - rmtree: /builds/slave/test/build 06:27:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:27:08 INFO - Running post-action listener: _resource_record_post_action 06:27:08 INFO - ##### 06:27:08 INFO - ##### Running read-buildbot-config step. 06:27:08 INFO - ##### 06:27:08 INFO - Running pre-action listener: _resource_record_pre_action 06:27:08 INFO - Running main action method: read_buildbot_config 06:27:08 INFO - Using buildbot properties: 06:27:08 INFO - { 06:27:08 INFO - "project": "", 06:27:08 INFO - "product": "firefox", 06:27:08 INFO - "script_repo_revision": "production", 06:27:08 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 06:27:08 INFO - "repository": "", 06:27:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 06:27:08 INFO - "buildid": "20160526052739", 06:27:08 INFO - "pgo_build": "False", 06:27:08 INFO - "basedir": "/builds/slave/test", 06:27:08 INFO - "buildnumber": 325, 06:27:08 INFO - "slavename": "t-yosemite-r7-0052", 06:27:08 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 06:27:08 INFO - "platform": "yosemite_r7", 06:27:08 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 06:27:08 INFO - "branch": "mozilla-aurora", 06:27:08 INFO - "repo_path": "releases/mozilla-aurora", 06:27:08 INFO - "revision": "e6b03d71815222297fdf701695ddd2cc743b0a80", 06:27:08 INFO - "stage_platform": "macosx64", 06:27:08 INFO - "builduid": "5abc185371e04a09b9188384b544af66", 06:27:08 INFO - "slavebuilddir": "test" 06:27:08 INFO - } 06:27:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 06:27:08 INFO - Running post-action listener: _resource_record_post_action 06:27:08 INFO - ##### 06:27:08 INFO - ##### Running download-and-extract step. 06:27:08 INFO - ##### 06:27:08 INFO - Running pre-action listener: _resource_record_pre_action 06:27:08 INFO - Running main action method: download_and_extract 06:27:08 INFO - mkdir: /builds/slave/test/build/tests 06:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:27:08 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:27:08 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:27:08 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:27:08 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:27:08 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:27:11 INFO - Downloaded 1199 bytes. 06:27:11 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 06:27:11 INFO - Using the following test package requirements: 06:27:11 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 06:27:11 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:27:11 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 06:27:11 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:27:11 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 06:27:11 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:27:11 INFO - u'jsshell-mac.zip'], 06:27:11 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:27:11 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 06:27:11 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 06:27:11 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:27:11 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 06:27:11 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:27:11 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 06:27:11 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:27:11 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 06:27:11 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 06:27:11 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 06:27:11 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 06:27:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:27:11 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:27:11 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:27:11 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:27:11 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:27:11 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:27:12 INFO - Downloaded 18425406 bytes. 06:27:12 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:27:12 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:27:16 INFO - Return code: 0 06:27:16 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:27:16 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:16 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:27:16 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:27:16 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:27:16 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:27:16 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:27:17 INFO - Downloaded 18425406 bytes. 06:27:17 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:27:17 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:27:21 INFO - Return code: 0 06:27: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:27:21 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:27:21 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:27:21 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:27:21 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:27:21 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:27:22 INFO - Downloaded 10840145 bytes. 06:27:22 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:27:22 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:27:22 INFO - Return code: 0 06:27:22 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:22 INFO - https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 06:27:22 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:27:22 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:27:22 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:27:22 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:27:25 INFO - Downloaded 114133909 bytes. 06:27:25 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:27:25 INFO - mkdir: /builds/slave/test/properties 06:27:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:27:25 INFO - Writing to file /builds/slave/test/properties/build_url 06:27:25 INFO - Contents: 06:27:25 INFO - build_url:https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 06:27:27 INFO - Running post-action listener: _resource_record_post_action 06:27:27 INFO - Running post-action listener: set_extra_try_arguments 06:27:27 INFO - ##### 06:27:27 INFO - ##### Running populate-webroot step. 06:27:27 INFO - ##### 06:27:27 INFO - Running pre-action listener: _resource_record_pre_action 06:27:27 INFO - Running main action method: populate_webroot 06:27:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:27:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:27:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:27:27 INFO - '--tppagecycles', 06:27:27 INFO - '7'], 06:27:27 INFO - 'tests': ['tsvgm']}}, 06:27:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:27:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:27:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:27:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:27:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:27:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:27:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:27:27 INFO - 'talos_options': ['--disable-e10s'], 06:27:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:27:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:27:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:27:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:27:27 INFO - 'talos_options': ['--disable-e10s'], 06:27:27 INFO - 'tests': ['damp', 'tps']}, 06:27:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:27:27 INFO - 'tests': ['damp', 'tps']}, 06:27:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:27:27 INFO - 'tests': ['dromaeo_dom']}, 06:27:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:27:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:27:27 INFO - 'tests': ['a11yr', 06:27:27 INFO - 'ts_paint', 06:27:27 INFO - 'tpaint', 06:27:27 INFO - 'sessionrestore', 06:27:27 INFO - 'sessionrestore_no_auto_restore', 06:27:27 INFO - 'tabpaint']}, 06:27:27 INFO - 'other-e10s': {'tests': ['a11yr', 06:27:27 INFO - 'ts_paint', 06:27:27 INFO - 'tpaint', 06:27:27 INFO - 'sessionrestore', 06:27:27 INFO - 'sessionrestore_no_auto_restore', 06:27:27 INFO - 'tabpaint']}, 06:27:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:27:27 INFO - 'ts_paint', 06:27:27 INFO - 'tpaint', 06:27:27 INFO - 'sessionrestore', 06:27:27 INFO - 'sessionrestore_no_auto_restore', 06:27:27 INFO - 'tabpaint']}, 06:27:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:27:27 INFO - 'ts_paint', 06:27:27 INFO - 'tpaint', 06:27:27 INFO - 'sessionrestore', 06:27:27 INFO - 'sessionrestore_no_auto_restore', 06:27:27 INFO - 'tabpaint']}, 06:27:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:27:27 INFO - 'tests': ['a11yr', 06:27:27 INFO - 'ts_paint', 06:27:27 INFO - 'tpaint', 06:27:27 INFO - 'sessionrestore', 06:27:27 INFO - 'sessionrestore_no_auto_restore', 06:27:27 INFO - 'tabpaint']}, 06:27:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:27:27 INFO - 'tests': ['a11yr', 06:27:27 INFO - 'ts_paint', 06:27:27 INFO - 'tpaint', 06:27:27 INFO - 'sessionrestore', 06:27:27 INFO - 'sessionrestore_no_auto_restore', 06:27:27 INFO - 'tabpaint']}, 06:27:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:27:27 INFO - 'tests': ['tsvgx', 06:27:27 INFO - 'tsvgr_opacity', 06:27:27 INFO - 'tart', 06:27:27 INFO - 'tscrollx', 06:27:27 INFO - 'cart']}, 06:27:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:27:27 INFO - 'tsvgr_opacity', 06:27:27 INFO - 'tart', 06:27:27 INFO - 'tscrollx', 06:27:27 INFO - 'cart']}, 06:27:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:27:27 INFO - 'talos_options': ['--disable-e10s'], 06:27:27 INFO - 'tests': ['tp5o']}, 06:27:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:27:27 INFO - 'tests': ['tp5o']}, 06:27:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:27:27 INFO - 'talos_options': ['--disable-e10s', 06:27:27 INFO - '--xperf_path', 06:27:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:27:27 INFO - 'tests': ['tp5n']}, 06:27:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:27:27 INFO - 'talos_options': ['--xperf_path', 06:27:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:27:27 INFO - 'tests': ['tp5n']}}, 06:27:27 INFO - 'talos.zip': {'path': '', 06:27:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:27:27 INFO - Running post-action listener: _resource_record_post_action 06:27:27 INFO - ##### 06:27:27 INFO - ##### Running create-virtualenv step. 06:27:27 INFO - ##### 06:27:27 INFO - Running pre-action listener: _resource_record_pre_action 06:27:27 INFO - Running main action method: create_virtualenv 06:27:27 INFO - Creating virtualenv /builds/slave/test/build/venv 06:27:27 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:27:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:27:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:27:27 INFO - Using real prefix '/tools/python27' 06:27:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:27:27 INFO - Installing distribute.............................................................................................................................................................................................done. 06:27:30 INFO - Installing pip.................done. 06:27:30 INFO - Return code: 0 06:27:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:27: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:27:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27: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:27:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:30 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 0x10676da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10677d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff588c80e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10660ce00>, '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 0x106475db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106607b20>, '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.UMOdv6bOau/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.aotvLwAaYc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:27:30 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:27:30 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:27:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UMOdv6bOau/Render', 06:27:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:30 INFO - 'HOME': '/Users/cltbld', 06:27:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:30 INFO - 'LOGNAME': 'cltbld', 06:27:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:30 INFO - 'MOZ_NO_REMOTE': '1', 06:27:30 INFO - 'NO_EM_RESTART': '1', 06:27:30 INFO - 'PAGER': '/bin/cat', 06:27:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:30 INFO - 'PWD': '/builds/slave/test', 06:27:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:30 INFO - 'SHELL': '/bin/bash', 06:27:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aotvLwAaYc/Listeners', 06:27:30 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 06:27:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:30 INFO - 'USER': 'cltbld', 06:27:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:30 INFO - 'XPC_FLAGS': '0x0', 06:27:30 INFO - 'XPC_SERVICE_NAME': '0', 06:27:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:30 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:27:30 INFO - Cleaning up... 06:27:30 INFO - Return code: 0 06:27:30 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 06:27: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:27:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27: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:27:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:30 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 0x10676da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10677d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff588c80e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10660ce00>, '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 0x106475db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106607b20>, '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.UMOdv6bOau/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.aotvLwAaYc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:27:30 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:27:30 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:27:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UMOdv6bOau/Render', 06:27:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:30 INFO - 'HOME': '/Users/cltbld', 06:27:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:30 INFO - 'LOGNAME': 'cltbld', 06:27:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:30 INFO - 'MOZ_NO_REMOTE': '1', 06:27:30 INFO - 'NO_EM_RESTART': '1', 06:27:30 INFO - 'PAGER': '/bin/cat', 06:27:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:30 INFO - 'PWD': '/builds/slave/test', 06:27:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:30 INFO - 'SHELL': '/bin/bash', 06:27:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aotvLwAaYc/Listeners', 06:27:30 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 06:27:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:30 INFO - 'USER': 'cltbld', 06:27:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:30 INFO - 'XPC_FLAGS': '0x0', 06:27:30 INFO - 'XPC_SERVICE_NAME': '0', 06:27:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:31 INFO - Downloading/unpacking psutil>=0.7.1 06:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:27:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:27:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:27:34 INFO - Installing collected packages: psutil 06:27:34 INFO - Running setup.py install for psutil 06:27:34 INFO - building 'psutil._psutil_osx' extension 06:27:34 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:27:34 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:27:34 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:27:34 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:27:34 INFO - building 'psutil._psutil_posix' extension 06:27:34 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:27:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:27:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:27:34 INFO - ^ 06:27:34 INFO - 1 warning generated. 06:27:34 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:27:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:27:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:27:35 INFO - Successfully installed psutil 06:27:35 INFO - Cleaning up... 06:27:35 INFO - Return code: 0 06:27:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 06:27: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:27:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27: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:27:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:35 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 0x10676da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10677d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff588c80e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10660ce00>, '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 0x106475db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106607b20>, '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.UMOdv6bOau/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.aotvLwAaYc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:27:35 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:27:35 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:27:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UMOdv6bOau/Render', 06:27:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:35 INFO - 'HOME': '/Users/cltbld', 06:27:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:35 INFO - 'LOGNAME': 'cltbld', 06:27:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:35 INFO - 'MOZ_NO_REMOTE': '1', 06:27:35 INFO - 'NO_EM_RESTART': '1', 06:27:35 INFO - 'PAGER': '/bin/cat', 06:27:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:35 INFO - 'PWD': '/builds/slave/test', 06:27:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:35 INFO - 'SHELL': '/bin/bash', 06:27:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aotvLwAaYc/Listeners', 06:27:35 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 06:27:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:35 INFO - 'USER': 'cltbld', 06:27:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:35 INFO - 'XPC_FLAGS': '0x0', 06:27:35 INFO - 'XPC_SERVICE_NAME': '0', 06:27:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:27:35 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:27:35 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:27:35 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:27:35 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:27:35 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:27:35 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:27:37 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:27:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:27:37 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:27:37 INFO - Installing collected packages: mozsystemmonitor 06:27:37 INFO - Running setup.py install for mozsystemmonitor 06:27:38 INFO - Successfully installed mozsystemmonitor 06:27:38 INFO - Cleaning up... 06:27:38 INFO - Return code: 0 06:27:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:27:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:38 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 0x10676da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10677d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff588c80e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10660ce00>, '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 0x106475db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106607b20>, '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.UMOdv6bOau/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.aotvLwAaYc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:27:38 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:27:38 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:27:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UMOdv6bOau/Render', 06:27:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:38 INFO - 'HOME': '/Users/cltbld', 06:27:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:38 INFO - 'LOGNAME': 'cltbld', 06:27:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:38 INFO - 'MOZ_NO_REMOTE': '1', 06:27:38 INFO - 'NO_EM_RESTART': '1', 06:27:38 INFO - 'PAGER': '/bin/cat', 06:27:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:38 INFO - 'PWD': '/builds/slave/test', 06:27:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:38 INFO - 'SHELL': '/bin/bash', 06:27:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aotvLwAaYc/Listeners', 06:27:38 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 06:27:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:38 INFO - 'USER': 'cltbld', 06:27:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:38 INFO - 'XPC_FLAGS': '0x0', 06:27:38 INFO - 'XPC_SERVICE_NAME': '0', 06:27:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:38 INFO - Downloading/unpacking blobuploader==1.2.4 06:27: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:27: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:27: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:27: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:27: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:27: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:27:40 INFO - Downloading blobuploader-1.2.4.tar.gz 06:27:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:27:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:27:40 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:27:40 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:27:40 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:27:40 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:27:40 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:27:40 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:27:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:27:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:27:41 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:27:41 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:27:41 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:27:41 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:27:41 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:27:41 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:27:41 INFO - Downloading docopt-0.6.1.tar.gz 06:27:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:27:42 INFO - Installing collected packages: blobuploader, requests, docopt 06:27:42 INFO - Running setup.py install for blobuploader 06:27:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:27:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:27:42 INFO - Running setup.py install for requests 06:27:42 INFO - Running setup.py install for docopt 06:27:42 INFO - Successfully installed blobuploader requests docopt 06:27:42 INFO - Cleaning up... 06:27:42 INFO - Return code: 0 06:27:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:27:42 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:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:42 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:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:42 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 0x10676da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10677d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff588c80e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10660ce00>, '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 0x106475db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106607b20>, '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.UMOdv6bOau/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.aotvLwAaYc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:27:42 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:27:42 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:27:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UMOdv6bOau/Render', 06:27:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:42 INFO - 'HOME': '/Users/cltbld', 06:27:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:42 INFO - 'LOGNAME': 'cltbld', 06:27:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:42 INFO - 'MOZ_NO_REMOTE': '1', 06:27:42 INFO - 'NO_EM_RESTART': '1', 06:27:42 INFO - 'PAGER': '/bin/cat', 06:27:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:42 INFO - 'PWD': '/builds/slave/test', 06:27:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:42 INFO - 'SHELL': '/bin/bash', 06:27:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aotvLwAaYc/Listeners', 06:27:42 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 06:27:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:42 INFO - 'USER': 'cltbld', 06:27:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:42 INFO - 'XPC_FLAGS': '0x0', 06:27:42 INFO - 'XPC_SERVICE_NAME': '0', 06:27:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:27:43 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-T_QV5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:27:43 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-b36yvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:27:43 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-6Cg8In-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:27:43 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-EnAcrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:27:43 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-7GFR9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:27:43 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-xXeoZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:27:43 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-BcJaTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:27:43 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-6OBqSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:27:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-PBORNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:27:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Ih68Ua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:27:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-VxB5iw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:27:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-S8BMct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:27:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-EvulGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:27:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-3mNIPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:27:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-qqrBqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:27:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-ngCn_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:27:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-kBxE7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:27:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:27:44 INFO - Running setup.py install for manifestparser 06:27:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:27:45 INFO - Running setup.py install for mozcrash 06:27:45 INFO - Running setup.py install for mozdebug 06:27:45 INFO - Running setup.py install for mozdevice 06:27:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:27:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:27:45 INFO - Running setup.py install for mozfile 06:27:45 INFO - Running setup.py install for mozhttpd 06:27:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:27:45 INFO - Running setup.py install for mozinfo 06:27:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:27:45 INFO - Running setup.py install for mozInstall 06:27:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:27:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:27:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:27:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:27:46 INFO - Running setup.py install for mozleak 06:27:46 INFO - Running setup.py install for mozlog 06:27:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:27:46 INFO - Running setup.py install for moznetwork 06:27:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:27:46 INFO - Running setup.py install for mozprocess 06:27:46 INFO - Running setup.py install for mozprofile 06:27:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:27:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:27:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:27:46 INFO - Running setup.py install for mozrunner 06:27:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:27:47 INFO - Running setup.py install for mozscreenshot 06:27:47 INFO - Running setup.py install for moztest 06:27:47 INFO - Running setup.py install for mozversion 06:27:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:27:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:27:47 INFO - Cleaning up... 06:27:47 INFO - Return code: 0 06:27:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:27:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:27:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:47 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 0x10676da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10677d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff588c80e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10660ce00>, '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 0x106475db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106607b20>, '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.UMOdv6bOau/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.aotvLwAaYc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:27:47 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:27:47 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:27:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UMOdv6bOau/Render', 06:27:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:47 INFO - 'HOME': '/Users/cltbld', 06:27:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:47 INFO - 'LOGNAME': 'cltbld', 06:27:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:47 INFO - 'MOZ_NO_REMOTE': '1', 06:27:47 INFO - 'NO_EM_RESTART': '1', 06:27:47 INFO - 'PAGER': '/bin/cat', 06:27:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:47 INFO - 'PWD': '/builds/slave/test', 06:27:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:47 INFO - 'SHELL': '/bin/bash', 06:27:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aotvLwAaYc/Listeners', 06:27:47 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 06:27:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:47 INFO - 'USER': 'cltbld', 06:27:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:47 INFO - 'XPC_FLAGS': '0x0', 06:27:47 INFO - 'XPC_SERVICE_NAME': '0', 06:27:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:27:47 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-QuaZOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:27:47 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:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:27:47 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-kKiY6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:27:47 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:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:27:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-JAHbq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:27:48 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:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:27:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-CcFkyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:27:48 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:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:27:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-JKDalF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:27:48 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:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:27:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-l0A1Bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:27:48 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:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:27:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-T14tit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:27:48 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:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:27:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-i_63fn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:27:48 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:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:27:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-thglFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:27:48 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:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:27:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-_GbRBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:27:48 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:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:27:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-6d8EvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:27:48 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:27:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:27:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-2Xgg6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:27:49 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:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:27:49 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-umm7Ix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:27:49 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:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:27:49 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Mw5ar8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:27:49 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:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:27:49 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-ngT6Zi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:27:49 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:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:27:49 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-CUIa8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:27:49 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:27:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:27:49 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-WIJwR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:27:49 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:27:49 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:27:49 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:27:49 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:27:49 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:27:49 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:27:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:52 INFO - Downloading blessings-1.6.tar.gz 06:27:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:27:52 INFO - Installing collected packages: blessings 06:27:52 INFO - Running setup.py install for blessings 06:27:52 INFO - Successfully installed blessings 06:27:52 INFO - Cleaning up... 06:27:52 INFO - Return code: 0 06:27:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:27:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:27:52 INFO - Reading from file tmpfile_stdout 06:27:52 INFO - Current package versions: 06:27:52 INFO - blessings == 1.6 06:27:52 INFO - blobuploader == 1.2.4 06:27:52 INFO - docopt == 0.6.1 06:27:52 INFO - manifestparser == 1.1 06:27:52 INFO - mozInstall == 1.12 06:27:52 INFO - mozcrash == 0.17 06:27:52 INFO - mozdebug == 0.1 06:27:52 INFO - mozdevice == 0.48 06:27:52 INFO - mozfile == 1.2 06:27:52 INFO - mozhttpd == 0.7 06:27:52 INFO - mozinfo == 0.9 06:27:52 INFO - mozleak == 0.1 06:27:52 INFO - mozlog == 3.1 06:27:52 INFO - moznetwork == 0.27 06:27:52 INFO - mozprocess == 0.22 06:27:52 INFO - mozprofile == 0.28 06:27:52 INFO - mozrunner == 6.11 06:27:52 INFO - mozscreenshot == 0.1 06:27:52 INFO - mozsystemmonitor == 0.0 06:27:52 INFO - moztest == 0.7 06:27:52 INFO - mozversion == 1.4 06:27:52 INFO - psutil == 3.1.1 06:27:52 INFO - requests == 1.2.3 06:27:52 INFO - wsgiref == 0.1.2 06:27:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:52 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 0x10676da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10677d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff588c80e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10660ce00>, '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 0x106475db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106607b20>, '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.UMOdv6bOau/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.aotvLwAaYc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:27:52 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:27:52 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:27:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UMOdv6bOau/Render', 06:27:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:52 INFO - 'HOME': '/Users/cltbld', 06:27:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:52 INFO - 'LOGNAME': 'cltbld', 06:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:52 INFO - 'MOZ_NO_REMOTE': '1', 06:27:52 INFO - 'NO_EM_RESTART': '1', 06:27:52 INFO - 'PAGER': '/bin/cat', 06:27:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:52 INFO - 'PWD': '/builds/slave/test', 06:27:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:52 INFO - 'SHELL': '/bin/bash', 06:27:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aotvLwAaYc/Listeners', 06:27:52 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 06:27:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:52 INFO - 'USER': 'cltbld', 06:27:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:52 INFO - 'XPC_FLAGS': '0x0', 06:27:52 INFO - 'XPC_SERVICE_NAME': '0', 06:27:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:52 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:27:52 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:27:52 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:27:52 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:27:52 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:27:52 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:27:52 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:27:52 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:27:52 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:27:52 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:27:52 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:27:52 INFO - Cleaning up... 06:27:52 INFO - Return code: 0 06:27:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10676da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10677d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff588c80e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10660ce00>, '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 0x106475db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106607b20>, '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.UMOdv6bOau/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.aotvLwAaYc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:27:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:27:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UMOdv6bOau/Render', 06:27:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:52 INFO - 'HOME': '/Users/cltbld', 06:27:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:52 INFO - 'LOGNAME': 'cltbld', 06:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:52 INFO - 'MOZ_NO_REMOTE': '1', 06:27:52 INFO - 'NO_EM_RESTART': '1', 06:27:52 INFO - 'PAGER': '/bin/cat', 06:27:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:52 INFO - 'PWD': '/builds/slave/test', 06:27:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:52 INFO - 'SHELL': '/bin/bash', 06:27:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aotvLwAaYc/Listeners', 06:27:52 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 06:27:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:52 INFO - 'USER': 'cltbld', 06:27:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:52 INFO - 'XPC_FLAGS': '0x0', 06:27:52 INFO - 'XPC_SERVICE_NAME': '0', 06:27:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:53 INFO - Downloading/unpacking jsonschema 06:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:27:55 INFO - Downloading/unpacking functools32 (from jsonschema) 06:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:55 INFO - Downloading functools32-3.2.3-2.tar.gz 06:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:27:56 INFO - warning: no files found matching '*.txt' 06:27:56 INFO - no previously-included directories found matching 'build' 06:27:56 INFO - no previously-included directories found matching 'dist' 06:27:56 INFO - no previously-included directories found matching '.git*' 06:27:56 INFO - Installing collected packages: jsonschema, functools32 06:27:56 INFO - Running setup.py install for functools32 06:27:56 INFO - warning: no files found matching '*.txt' 06:27:56 INFO - no previously-included directories found matching 'build' 06:27:56 INFO - no previously-included directories found matching 'dist' 06:27:56 INFO - no previously-included directories found matching '.git*' 06:27:56 INFO - Successfully installed jsonschema functools32 06:27:56 INFO - Cleaning up... 06:27:56 INFO - Return code: 0 06:27:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:27:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:56 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 0x10676da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10677d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff588c80e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10660ce00>, '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 0x106475db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106607b20>, '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.UMOdv6bOau/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.aotvLwAaYc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:27:56 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:27:56 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:27:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UMOdv6bOau/Render', 06:27:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:56 INFO - 'HOME': '/Users/cltbld', 06:27:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:56 INFO - 'LOGNAME': 'cltbld', 06:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:56 INFO - 'MOZ_NO_REMOTE': '1', 06:27:56 INFO - 'NO_EM_RESTART': '1', 06:27:56 INFO - 'PAGER': '/bin/cat', 06:27:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:56 INFO - 'PWD': '/builds/slave/test', 06:27:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:56 INFO - 'SHELL': '/bin/bash', 06:27:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aotvLwAaYc/Listeners', 06:27:56 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 06:27:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:56 INFO - 'USER': 'cltbld', 06:27:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:56 INFO - 'XPC_FLAGS': '0x0', 06:27:56 INFO - 'XPC_SERVICE_NAME': '0', 06:27:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:56 INFO - Downloading/unpacking flake8 06:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:27:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:27:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:28:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:28:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:28:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:28:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:28:00 INFO - no previously-included directories found matching 'docs/_build' 06:28:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:28:00 INFO - Running setup.py install for pep8 06:28:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:28:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:28:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:28:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:28:00 INFO - no previously-included directories found matching 'docs/_build' 06:28:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:28:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:28:00 INFO - Cleaning up... 06:28:00 INFO - Return code: 0 06:28:00 INFO - Running post-action listener: _resource_record_post_action 06:28:00 INFO - Running post-action listener: _start_resource_monitoring 06:28:00 INFO - Starting resource monitoring. 06:28:00 INFO - ##### 06:28:00 INFO - ##### Running install step. 06:28:00 INFO - ##### 06:28:00 INFO - Running pre-action listener: _resource_record_pre_action 06:28:00 INFO - Running main action method: install 06:28:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:28:01 INFO - Reading from file tmpfile_stdout 06:28:01 INFO - Detecting whether we're running mozinstall >=1.0... 06:28:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:28:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:28:01 INFO - Reading from file tmpfile_stdout 06:28:01 INFO - Output received: 06:28:01 INFO - Usage: mozinstall [options] installer 06:28:01 INFO - Options: 06:28:01 INFO - -h, --help show this help message and exit 06:28:01 INFO - -d DEST, --destination=DEST 06:28:01 INFO - Directory to install application into. [default: 06:28:01 INFO - "/builds/slave/test"] 06:28:01 INFO - --app=APP Application being installed. [default: firefox] 06:28:01 INFO - mkdir: /builds/slave/test/build/application 06:28:01 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:28:01 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:28:33 INFO - Reading from file tmpfile_stdout 06:28:33 INFO - Output received: 06:28:33 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 06:28:33 INFO - Running post-action listener: _resource_record_post_action 06:28:33 INFO - ##### 06:28:33 INFO - ##### Running run-tests step. 06:28:33 INFO - ##### 06:28:33 INFO - Running pre-action listener: _resource_record_pre_action 06:28:33 INFO - Running main action method: run_tests 06:28:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:28:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:28:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:28:33 INFO - 2016-05-26 06:28:33.232 screenresolution[1925:15303] starting screenresolution argv=screenresolution get 06:28:33 INFO - 2016-05-26 06:28:33.234 screenresolution[1925:15303] Display 0: 1600x1200x32@60 06:28:33 INFO - 2016-05-26 06:28:33.240 screenresolution[1926:15305] starting screenresolution argv=screenresolution list 06:28:33 INFO - Available Modes on Display 0 06:28:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:28:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:28:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:28:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:28:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:28:33 INFO - Intel Iris: 06:28:33 INFO - Chipset Model: Intel Iris 06:28:33 INFO - Type: GPU 06:28:33 INFO - Bus: Built-In 06:28:33 INFO - VRAM (Dynamic, Max): 1536 MB 06:28:33 INFO - Vendor: Intel (0x8086) 06:28:33 INFO - Device ID: 0x0a2e 06:28:33 INFO - Revision ID: 0x0009 06:28:33 INFO - Displays: 06:28:33 INFO - Display: 06:28:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:28:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:28:33 INFO - Display Serial Number: 1600x1200 60 06:28:33 INFO - Main Display: Yes 06:28:33 INFO - Mirror: Off 06:28:33 INFO - Online: Yes 06:28:33 INFO - Rotation: Supported 06:28:33 INFO - Return code: 0 06:28:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:28:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:28:33 INFO - Python 2.7.3 06:28:33 INFO - Return code: 0 06:28:33 INFO - grabbing minidump binary from tooltool 06:28:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:28:33 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 0x10660ce00>, '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 0x106475db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106607b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:28:33 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:28:33 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:28:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:28:33 INFO - Return code: 0 06:28:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:28:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:28:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:28:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:28:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:28:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:28:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:28:34 INFO - Return code: 0 06:28:34 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', '--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-0052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 06:28:34 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 --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-0052 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 06:28:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UMOdv6bOau/Render', 06:28:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:34 INFO - 'HOME': '/Users/cltbld', 06:28:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:34 INFO - 'LOGNAME': 'cltbld', 06:28:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:28:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:28:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:34 INFO - 'MOZ_NO_REMOTE': '1', 06:28:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:28:34 INFO - 'NO_EM_RESTART': '1', 06:28:34 INFO - 'PAGER': '/bin/cat', 06:28:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:34 INFO - 'PWD': '/builds/slave/test', 06:28:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:28:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:34 INFO - 'SHELL': '/bin/bash', 06:28:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aotvLwAaYc/Listeners', 06:28:34 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 06:28:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:34 INFO - 'USER': 'cltbld', 06:28:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:34 INFO - 'XPC_FLAGS': '0x0', 06:28:34 INFO - 'XPC_SERVICE_NAME': '0', 06:28:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--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-0052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 06:28:34 INFO - mozversion application_buildid: 20160526052739 06:28:34 INFO - mozversion application_changeset: e6b03d71815222297fdf701695ddd2cc743b0a80 06:28:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:28:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:28:34 INFO - mozversion application_name: Firefox 06:28:34 INFO - mozversion application_remotingname: firefox-dev 06:28:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:28:34 INFO - mozversion application_vendor: Mozilla 06:28:34 INFO - mozversion application_version: 48.0a2 06:28:34 INFO - mozversion platform_buildid: 20160526052739 06:28:34 INFO - mozversion platform_changeset: e6b03d71815222297fdf701695ddd2cc743b0a80 06:28:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:28:34 INFO - mozversion platform_version: 48.0a2 06:28:34 INFO - using testdate: 1464269314 06:28:34 INFO - actual date: 1464269314 06:28:34 INFO - starting webserver on 'localhost:52119' 06:28:34 INFO - SUITE-START | Running 2 tests 06:28:34 INFO - TEST-START | dromaeo_css 06:28:34 INFO - operating with platform_type : mac_ 06:28:34 INFO - Initialising browser for dromaeo_css test... 06:28:34 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpWbkpOD/profile http://localhost:52119/getInfo.html) 06:28:37 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 06:28:37 INFO - PROCESS | 1944 | colorDepth:24 06:28:37 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 06:28:37 INFO - PROCESS | 1944 | __metrics 06:28:37 INFO - PROCESS | 1944 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:28:37 ERROR - PROCESS | 1944 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:28:38 INFO - TEST-INFO | 1944: exit 0 06:28:38 INFO - Browser initialized. 06:28:38 INFO - Running cycle 1/1 for dromaeo_css test... 06:28:38 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpWbkpOD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:28:49 INFO - PROCESS | 1946 | RSS: Main: 211193856 06:28:49 INFO - PROCESS | 1946 | 06:29:41 INFO - PROCESS | 1946 | 1464269381390 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:31:52 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52119/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52119/tests/dromaeo/cssquery-ext.html) 06:31:53 INFO - PROCESS | 1946 | RSS: Main: 318517248 06:31:53 INFO - PROCESS | 1946 | 06:34:56 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52119/tests/dromaeo/cssquery-ext.html (next: http://localhost:52119/tests/dromaeo/cssquery-jquery.html) 06:34:57 INFO - PROCESS | 1946 | RSS: Main: 356089856 06:34:57 INFO - PROCESS | 1946 | 06:37:59 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52119/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52119/tests/dromaeo/cssquery-mootools.html) 06:37:59 INFO - PROCESS | 1946 | RSS: Main: 364101632 06:37:59 INFO - PROCESS | 1946 | 06:41:01 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52119/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52119/tests/dromaeo/cssquery-prototype.html) 06:41:02 INFO - PROCESS | 1946 | RSS: Main: 361771008 06:41:02 INFO - PROCESS | 1946 | 06:44:05 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52119/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52119/tests/dromaeo/cssquery-yui.html) 06:44:06 INFO - PROCESS | 1946 | RSS: Main: 331845632 06:44:06 INFO - PROCESS | 1946 | 06:47:08 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52119/tests/dromaeo/cssquery-yui.html (next: http://localhost:52119/tests/dromaeo/cssquery-dojo.html) 06:47:09 INFO - PROCESS | 1946 | RSS: Main: 367521792 06:47:09 INFO - PROCESS | 1946 | 06:47:09 INFO - PROCESS | 1946 | __start_tp_report 06:47:09 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 06:47:09 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 06:47:09 INFO - PROCESS | 1946 | |i|pagename|runs| 06:47:09 INFO - PROCESS | 1946 | |0;dojo.html;3313.884014059508;3399;3409.9147521311957;3432.5709286339206;3475.1138459692775;5532.889342213159;5537;5538.556915446765;5557.805476808313;5576.832695019151;5491.258680078198;5516.586256030803;5566.2207290979195;5571.888562228753;5650.0677388231;4957.132501812186;5054.646174767777;5059.822906198284;5134.845954621349;5188.247704082905;5086.313347698069;5171.534561889429;5172.120739474299;5182.093133701606;5195.298634684307;5109.923351149736;5125.820596279131;5136.871578210537;5165.845024649267;5173.767180476888;5138.511841375082;5177.404598471187;5194.220866869962;5200.687958722451;5239.921401178985;5033.395992480889;5041.218611115283;5100.69395836251;5116.411612664554;5118.232265160219;5439.048166570874;5439.864003399907;5447.373552041527;5499.312585926759;5515.420880807537;6047.78832740852;6057;6085.208922840003;6088.847778805521;6105.9694701526205;5118.283440318359;5969.880602387928;6092.66490343031;6115.235595550556;6199.473044791198;4806.735629540409;5545.306836645419;5614.578906581982;5621.578381621394;5649.915251271234;6256.843578910563;6274.881990059746;6292.716827742763;6339.144215530844;6339.461145802658;5809.070548712186;5911.76352945886;5950.732217050243;6003.339632640406;6026.035834266584;5138.2806407103035;6039.335673075958;6079.513638908877;6081.939180608226;6116.510679145657;5065.746712664352;5985.102234664835;6005.729742161613;6017.127516510169;6020.096985452217;5900.1739756434135;5989.341172471025;6028.9397106029255;6045.6070355426755;6075.817725468155;4742.5731684148595;4794.640401969867;4812.590929771009;4814.229723244334;4823.589994850477;4344.326629372453;4360.716553424018;4365.170617582713;4389.385486031958;4394.472663280363;5534.086875665551;5588.245586845724;5588.552915766729;5596.384397716248;5620.831375058755;6105.8168254952425;6126.754929802847;6150.692465376803;6166.167567378347;6184.165137706442;5429.864253393696;5568.972155139199;5577.107662774018;5577.581681373913;5591.049521581341;5223.738813059332;5245.606579506476;5251.212318152231;5281.973590132025;5291.444398338119;6306.527010474325;6334.239891212992;6343.524235682342;6343.524235682342;6350.682465876687;5964.701764911824;6035.064564992434;6098;6113.786017489367;6116;5224.764885580082;6264.373562643699;6338.429541341301;6342.873142537216;6390.297067322498;6045.78839740607;6064.818055458343;6068.605540639845;6070;6071.726772295346;5967;6162.876742465215;6169.81490555284;6175.042868355412;6197.628142311476;4913.213885778258;5851.882962340645;5861;5892.646441213541;5920.674362910081;4489.618382437529;5863.794767183128;5867.882642347215;5878.470937615635;5879.176915231786;5536.809585939042;5587.888242235052;5723.656580605177;5740.598158128891;5772.451617096369;5881.5588830837005;5891.793787217598;5894.145348924468;5928.8814223716145;5933.050711886078;4818.71087734737;5792.565557583114;5794.985877471341;5800.1299805029585;5806.970965145148 06:47:09 INFO - PROCESS | 1946 | |1;ext.html;490.4996903158778;491.6288202407183;522.5401646551037;530.5384315645389;584.1821449970045;30805.537916931236;31564.368712625735;38358;38924.416133758015;38989.80505097478;41953.58046419535;42365;42369.364459533135;42384.78807605965;42530;8416.957915210416;9371.062893710625;9587.185089267387;9590.992945740702;9607.66373176939;8577.313814894824;9740.318177727562;11642.476088576035;11674.883251167506;11675.24110932791;11542.538298468051;11613.6515904523;11621.302721836717;11662.533498660043;11667.299962002264;8535.103814099555;8722.781930451789;8725.127487251224;11434.42827858608;11588.072954163647;11344;11558.653240402802;11572.6528204154;11574.479148438257;11576;38416.42375364344;38982.610173898465;38991;39028.804855975824;39072.413913791315;3328.151321413024;3340.46552551593;3533;3599.406097993828;3712.053426376257;26163.43019418841;26894.32764180911;28713.71286287152;28962.855185723936;29108.70891291081;2443.3402981195154;2731.9726802731916;2737.137801592492;2744.862756862149;2897.913062608125;333172;334089;334841.99999999756;336494.3175284108;336882;36252.918501927634;36792;36858;60487.69756151191;61027.779444410866;35055.29889402249;35183.4722479163;35358.823205883804;35393.29213415717;35426.822865886024;15599;15819.604509887115;15819.841801581837;15822;15847.762283565755;12285.815712764315;12473.064520161024;13164;13386.866131338757;13553.661158471117;8138.83722325562;8153.796155096052;8163.22449367321;8233.670653173805;8245.95877020611;3363.613184483767;3386.8983930482127;3403.9447771190517;3409.948850767191;3415.7267418606452;869.7303835810792;968.2883080935507;1025.6512785652915;1053.8189806156672;1105.8175401058736;30081.097567073022;30127;30496.847515762278;30746;30798.53802192969;959.5825815770162;970.9077637624414;1046.8429735539578;1074.4573990134932;1202.6211743301008;14285.42858285657;14545.34545945435;14610.465901080446;14920.104793712411;14959.626009349853;562266;626753;636190;637167;642617;61533.38466615366;62072.68963655243;62073.689631551555;62075.068873966025;62104.37895621076;1403.5368328451586;1464.209326963449;1481.2593703148425;1485.1831492678768;1505.4580351073823;27800.58299125474;29164.562531562467;29426.705732943254;29765.958191463196;29882.103536893927;46408.76795616;59965.70017150061;61049;61200;61444.38555614387;470475;472890;479396.60301698267;479596;480319;35343.29313413769;35630.821845890605;40295;42295.7885210572;46196;44321.55678443174;44397;45031.099378012914;46459.76770116263;46828.53171468378;36518.26963460663;37096.814515927246;37199.62800371962;37226.627733722315;39073.804630976665;36839.44740828943;36978;37006.39075015085;37778;37814.2437151261;36715.89852304435;37052.073698157765;37387.813060934524;37769.678061267725;38166.6183338163;31624;32014.199645009063;32093.679063210002;32105.75921424637;32388;24788.008479660613;24871.502569948138;25048.874755626835;25125.497490050464;25258.4844909306 06:47:09 INFO - PROCESS | 1946 | |2;jquery.html;330.6924560159051;388.69099066242336;400.4493820996129;401.3898873711958;402.61147992187534;15892.761608575851;16342.34630614774;19906.10422530986;20465.693014604774;21076;16276.918615406936;20123.698144527807;20668.793312066875;21098.050587723552;21126.366209013715;5117.181250999841;5898.557608179382;6067.393260673942;6069.453749162574;6147.661878596676;5985.6109352892145;6013.398660133974;6048.485878700316;6058.061000544901;6064.3026052003925;4444.97777511112;6026.367231440701;6027.578069535134;6028.668423236721;6034.939650603504;5904.793332233373;5915.852103697377;5937.168796368512;5939.376365481606;5941.851453713649;5577.637453565546;5675.574331925122;5729.997250481149;5746.798862039808;5748.45389687979;13253.933730331382;13470.797938030937;13601.387937542835;13685;13702.93148534261;2172.9565408691738;2247.370736193868;2268.3081660093667;2499.487605040962;2549.6175573663913;9215.03242159577;9592.808143837085;9797.167240784542;9898.653547125816;9905.554250058765;1742.8170042145518;1755.4119370011063;1774.1661419132986;1790.534461040126;1797.8561715062763;87559;116978.41510792477;117266;118044.40977795141;119649.20526192115;11268.09855211581;11678.189880568525;11697.23967942098;11712.063034957184;11917.523299068114;13282.53511127106;13852.376643051088;14942.25288735559;14975.32611032506;14994.175320357266;7977.683682192373;7986;8092.554909480035;8203.343732501386;8331.625076871554;8646.913530864736;8709.216170544554;8786.824263514694;8876.51179185138;8911;4571.2000399929875;4573.262160379035;4592.10453961481;4592.94362296667;4652.162610730101;1910.1308904448442;1965.518447980254;1966.7738210105736;1966.783653798081;1967.685170372762;885.8715486254457;967.6419724701906;973.3235401396104;1039.0804138337626;1052.2423854824515;19469.707954380694;19660.410187694393;19860.40418787439;20112;20388.694169587467;929.3726734454351;953.3136141977765;979.4661909259471;1014.031599822168;1091.6015654286286;8868.733937981871;10545.578176872992;10555.525001374957;10589.735256618493;10615.946920265504;589866;598229;604893;605196;606014;108955.45522272338;114026;120385.39807300907;121419;123482.38258808828;1010.7018429563296;1021.6219998600566;1028.5165971993151;1038.5949479702922;1049.2183323423944;17602;17667;17714.468565943043;17974.640507190044;18214.45356639303;51219.48780512147;51591.48408515867;51872.703182420744;53346.66633334198;53349.46650533445;52594;60420.39579604324;63838.3616163851;73858.26141738729;78625;7263.309985551364;7673.731419400294;7758.806029849073;7999.200079991954;8138.226868447488;10073.748156296151;11992.460339284578;12031;12306.323152226714;12317.938410307892;10234.795304094025;10400.011998860096;10421.322614030063;10485.633002844865;10608;9394.637777522357;9450.385724927857;10426.895731042592;10427.270091093551;10496.947515262375;10422;10483.790324193626;10670.946645267035;10747.086497647788;10871.456427178451;7844.372450204084;7861.489003214772;8005.319547838499;8073.111957684768;8082.959585202037;7888.2506161914525;8400.915990840012;10541.998510141524;10656.163781038666;10691.198160137865 06:47:09 INFO - PROCESS | 1946 | |3;mootools.html;1319.722858199778;1384.9958779884578;1512.1003003213084;1678.8992660440367;1918.7985261547528;7823.9217607823975;8507.702230421937;8685.174908383702;8687.305015598753;8692.826143477143;8340.165983401657;8631.093735157796;8651.961764588243;8705.216530512253;8747.387682862201;2127.361791462555;2346.9647955280684;2396.6405039244123;2413.9034438622434;2429.2226487524;2424.066734307287;2424.9393765155837;2425.042108367194;2425.842320249178;2427.3203503019095;2345.5895218336723;2378.3221781792195;2402.9158979435724;2404.95190096198;2405.1461731085474;2116.5978464091772;2355.7644235576477;2403.2429784617875;2404.495056038234;2405.9398515037087;2381.89281482332;2407.9759202408104;2408.3256688127353;2410.4937963027787;2413.4931664350506;8482.53346065966;8540.74377768668;8541.871871921927;8552.27305679018;8571.271441927382;5108.846734597968;5129.820456284012;5228.503292187274;5234.502722241381;5237.423883372826;3436.432988556885;3480.112571294329;3536.3104194682046;3553;3565.144365352323;1030.4697524176593;1036.7926414717012;1061.2571200159919;1062.0388548363744;1071.0521188748014;11724.413779311086;11746.412679365998;11764.2941423514;11764.882351176464;11785.941070294593;4208.284591619432;4389.736615803062;4698.1778188816825;4735.739534325645;4756.619470442356;9916.553755081039;9962;9965.501724913725;9977.201823854219;9978.002199779963;4606.838760643362;5306.52241298285;5316.202569614511;5344.331958505187;5357.678539287655;3655.8354874030733;4216.662666986634;4749.525047495292;4786.856394308176;4795.112904112751;3941.231459865356;3968.9603103969166;4006.3790112532606;4045.211183819186;4074.3684728867074;1316.8748968847945;1320.7622627926874;1321.717933604402;1330.1221194011905;1339.6316013096282;913.5614904845578;925.4169872979979;927.2350310993457;928.3223247029571;932.4964519159578;5711.457411545896;5864.618799778001;5884.705764711747;5920.259967504062;5928.318243401977;460.580871407018;463.9141758774638;465.28346346626495;469.07592043673907;481.5040508276481;3827.827747751358;3881.437191607201;3927.626875446828;3945.506811648544;3971.6425521703186;10246.129079028216;10552.103071238877;10627.84058239127;10679.73300667474;10681.679549613524;10109.747256318651;10545.789084218273;10642.627508037336;10656.307340022873;10677.466126693635;7931.127575966639;8046.316063134582;8186.7953301167945;8187.38594605407;8321.292690121287;5540.63985840919;5568.972155139199;5611.7194140292;5631.380548139783;5649.9830030594885;7390.704371825282;9326.673566425143;9364.016778238187;9513.952430237805;9521.523923803643;9204.585793639437;9704.980976997596;9722.416655000723;9775.902240977784;9786.119249267314;6579.539432239697;6721.596704197764;7131.108611423572;7455.739049133254;7520.661570229244;8064.233897779702;8091.959540202261;8163.346932245525;8168.918310816815;8203.179682031749;7103.573785572882;7203.099612548432;7224.458165637493;7335.889961650473;7374.225706300761;7110.608916509642;7124.5725256484775;7184.640767961477;7230.060092187982;7250.528715633467;6902.344277293649;7029.894551581628;7034.718611255493;7036.366726994595;7038.085048943604;4732.313814496947;4756.429228492603;4783.115123701987;4860.076585448754;4940.258961155711;4863.294822250683;4890.119778439915;4929.9014019720125;4994.875128121826;5104.515071068243 06:47:09 INFO - PROCESS | 1946 | |4;prototype.html;320.247418566369;323.47758370232066;329.64398449674354;332.4763497491453;336.4969370790668;5124.282600435938;5288.735563221833;6524.510661700374;6539.901901471476;6594.472442204624;6555.41001309882;6559.573627714195;6574.539782215247;6578.835529111775;6581.7696380626685;4647.814087436498;4719.858404247861;4756.857294281177;4841.3222148899;4871.683340582851;4774;4879.512048795127;4890.21756518957;4896.706197628154;4902.46072931979;4865.075635629237;4867.707937523742;4878.70727756334;4878.829240976566;4881.585065269456;3692.5384326958997;4115.9588404115875;4669.392978912737;4722.409698787652;4867.51324867514;4181.686373521968;4193.874183774492;4198.6641068714425;4202.957970420318;4211.05251318454;5071.1632580624155;6109.816705498843;6220.502359811204;6231.844203894893;6240.375962403723;5746.913796293059;5748.482636562688;5755.769769209227;5765.308163020422;5776.913346299809;6985.79042628722;6988.61562614056;6997.965010174917;7000.019997200396;7013.193482749498;4365.43249377582;4541.545845415465;4543.636509079266;4569;4587.564181402761;7265.8546829063125;7430.21059259776;7437.181909989897;7450.962745186293;7461.045645807676;4626.8149274028665;4631.698939568917;4645.6980296280635;4646.83736069236;4647.837325693585;4675.906481870344;4681.836135735233;4682.765861706969;4691.554302341272;4701.929471057937;5581.190727344512;5584.720763961867;5591.608587398924;5597.328320601554;5601.747921343549;6765.188177418741;7013.333733295329;7017;7028.92971070293;7065.2228254891925;1603.3907115296113;1621.9432319868984;1632.8856980011285;1633.3058450158635;1636.8363163683537;971.1453920550013;981.8183636027358;1018.7504061504978;1021.5249908792299;1046.638909576195;6468.967655161694;6607.471402287805;6673.366030227121;6682.231543372477;6685.364890335411;7397.482176247719;7414.110306763115;7419.777406677808;7426;7429.62851857405;6664.333566643394;6671.532992690562;6671.799846004627;6679.666016699146;6687.531872768859;3735.234276973214;3829.885103446901;3872.6127387261613;3874.2057878134915;3894.2211557688574;6313.810585682437;7709.575973321521;7748;7773.844523109507;7798.33714134305;7489.251074892467;7563.773086807405;7566.35685966688;7582.62086895653;7592.240775922474;7739.92260077392;7759.146493885668;7762.456628035983;7764.728234511765;7765.417593680497;7038.788836334918;7334.229905860021;7339.8532029360185;7350.558966462029;7358.889616655647;7164.605946672983;7216.3505284524645;7218.639068046472;7219.278072192738;7223.627510772936;7749.147593764794;7759.495632783851;7763.378929685725;7780.533168009937;7786;4648.907021859612;4650.627949764078;4650.697704649187;4651.395318608559;4659.184642687448;6357.809265722036;7034.47241456903;7303.342699157101;7306.780796576111;7311.159216689936;5878.294604647299;6502.804915852532;7029.683664235224;7030.402415794715;7056.6118863463;6729.730810767514;6985.615791131537;7005.439564834903;7027.227005029545;7035.613041282574;5612.326520817528;6875.3812156906115;6879.862402752017;6888.41448476885;6911.7926462206215;6837.555558888656;7000.929990700231;7016.438684905093;7032.367086962198;7120.857582848417;5634.802781902614;6610.2398224204835;6758.324167583202;6771.322867713189;6995.930040699528 06:47:09 INFO - PROCESS | 1946 | |5;yui.html;642.5791106825028;673.5184343194614;722.7073035420657;753.8266198774281;768.9269519395659;14732.263386830657;14867.479638212664;15097.320620572073;15116.244187790622;15122.395104195846;12247.32639704818;13667.248301343423;13879.930600346985;14040.578782636512;14053.437862485489;740.870347689155;752.8494301139766;899.815537814748;913.0595486648763;939.286142531674;928.5264515097315;930.2790337488433;934.4159900062461;934.8036912248436;935.7333160049387;966.84530475124;974.507873523873;975.4293738163183;975.4439969217549;976.2043934193609;776.343989329016;955.9962039858156;977.9217662586976;978.3542861711272;981.259149342242;712.9001939728442;715.5563550598662;715.9821004474878;716.1763971432841;717.9246179151166;14721.337539810735;14782.852171478362;14825.332860021217;14835.332410041574;14879.18164500952;12431.067669924701;12504.842410576779;12731.61805145847;12846.807297890538;13025;10017.549210285555;10174.135198508136;10202.846957295647;10244.641437549724;10319.793604127875;3490.214701692099;3507.5966263879727;3509.0350153707936;3576.928461430783;3581.83881725323;78098.82851757228;79780.40439191184;79860.20139798586;79886.20113798903;79972;9252.213561847318;9526.714198574055;9666.565004574812;9692.369995950241;9775.657851975147;22165.335039948826;22183.44541386446;22307.665385019238;22332.665010024863;22356.77643223547;7440;7571.205023472567;7642.69429222836;7667.53994760305;7750.689972401154;3548.4499902514635;4135.048938744046;4210.7473551587;4269.807858646368;4386.627136693353;3605.8197090145386;3671.541057367829;3672.5042119313553;3673.3204357194004;3676.2279921216846;641.8491654461197;651.5439192565224;656.9605823650596;668.0913957018452;674.9392554670005;520.6121439527501;550.3725752641991;589.9174115623816;600.5015836855399;619.3775255867824;7766.689332426753;7936.761897143095;7959.482633628795;8033.437659363788;8074.596270186585;570.6918264137403;578.1789858401081;587.2483221476517;592.8369698332907;619.0744836469507;3594.191306955914;3737.4206997915367;3746.662800347982;3840.1551658635058;3885.786281754474;79350;81427;81700;82123.7681434779;82463.58768206241;75422;75719;76472;77214.61392693112;77702;60289.39710602925;62500.68749656223;65562.68874622481;66163;66192.33807661958;6971.895421568782;7475.738349157753;7600.3539699123985;7643.235676432312;7688.769336919901;43864.34203486887;47702.76148619373;47815.28277075777;47925.5207447935;49411.75294123506;61305.69347153236;61862;62672;64547.35452645413;68506;8027;9737.610495580097;9751.122398984126;9919;10171;8591.790222275238;10726;10993.065589424989;11686.415679215836;12213.75572488563;11242.10063194926;11252.437378130897;11475.48360323804;11550.653480395602;11700.941495292469;11066.889331106586;11169.38568378747;11326.490307935997;11406.429678516208;11863.34751588671;10593.52329145175;10840.5121769519;11039.061679757311;11694.649160525198;11715.778161005792;9248.352615316864;9374;9647.565859536197;9668.081532254424;9669.854952175863;9365.157135857806;9427.764305892408;9440.055994400505;9458.716238512856;9477.905220947978 06:47:09 INFO - PROCESS | 1946 | __end_tp_report 06:47:09 INFO - PROCESS | 1946 | __start_cc_report 06:47:09 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,1022 06:47:09 INFO - PROCESS | 1946 | __end_cc_report 06:47:09 INFO - PROCESS | 1946 | __startTimestamp1464270429051__endTimestamp 06:47:09 INFO - PROCESS | 1946 | 06:47:09 INFO - PROCESS | 1946 | ------- Summary: start ------- 06:47:09 INFO - PROCESS | 1946 | Number of tests: 6 06:47:09 INFO - PROCESS | 1946 | 06:47:09 INFO - PROCESS | 1946 | [#0] dojo.html Cycles:165 Average:5572.86 Median:5649.92 stddev:624.14 (11.0%) stddev-sans-first:600.37 06:47:09 INFO - PROCESS | 1946 | Values: 3313.9 3399.0 3409.9 3432.6 3475.1 5532.9 5537.0 5538.6 5557.8 5576.8 5491.3 5516.6 5566.2 5571.9 5650.1 4957.1 5054.6 5059.8 5134.8 5188.2 5086.3 5171.5 5172.1 5182.1 5195.3 5109.9 5125.8 5136.9 5165.8 5173.8 5138.5 5177.4 5194.2 5200.7 5239.9 5033.4 5041.2 5100.7 5116.4 5118.2 5439.0 5439.9 5447.4 5499.3 5515.4 6047.8 6057.0 6085.2 6088.8 6106.0 5118.3 5969.9 6092.7 6115.2 6199.5 4806.7 5545.3 5614.6 5621.6 5649.9 6256.8 6274.9 6292.7 6339.1 6339.5 5809.1 5911.8 5950.7 6003.3 6026.0 5138.3 6039.3 6079.5 6081.9 6116.5 5065.7 5985.1 6005.7 6017.1 6020.1 5900.2 5989.3 6028.9 6045.6 6075.8 4742.6 4794.6 4812.6 4814.2 4823.6 4344.3 4360.7 4365.2 4389.4 4394.5 5534.1 5588.2 5588.6 5596.4 5620.8 6105.8 6126.8 6150.7 6166.2 6184.2 5429.9 5569.0 5577.1 5577.6 5591.0 5223.7 5245.6 5251.2 5282.0 5291.4 6306.5 6334.2 6343.5 6343.5 6350.7 5964.7 6035.1 6098.0 6113.8 6116.0 5224.8 6264.4 6338.4 6342.9 6390.3 6045.8 6064.8 6068.6 6070.0 6071.7 5967.0 6162.9 6169.8 6175.0 6197.6 4913.2 5851.9 5861.0 5892.6 5920.7 4489.6 5863.8 5867.9 5878.5 5879.2 5536.8 5587.9 5723.7 5740.6 5772.5 5881.6 5891.8 5894.1 5928.9 5933.1 4818.7 5792.6 5795.0 5800.1 5807.0 06:47:09 INFO - PROCESS | 1946 | 06:47:09 INFO - PROCESS | 1946 | [#1] ext.html Cycles:180 Average:61430.32 Median:35410.06 stddev:131474.16 (371.3%) stddev-sans-first:131763.36 06:47:09 INFO - PROCESS | 1946 | Values: 490.5 491.6 522.5 530.5 584.2 30805.5 31564.4 38358.0 38924.4 38989.8 41953.6 42365.0 42369.4 42384.8 42530.0 8417.0 9371.1 9587.2 9591.0 9607.7 8577.3 9740.3 11642.5 11674.9 11675.2 11542.5 11613.7 11621.3 11662.5 11667.3 8535.1 8722.8 8725.1 11434.4 11588.1 11344.0 11558.7 11572.7 11574.5 11576.0 38416.4 38982.6 38991.0 39028.8 39072.4 3328.2 3340.5 3533.0 3599.4 3712.1 26163.4 26894.3 28713.7 28962.9 29108.7 2443.3 2732.0 2737.1 2744.9 2897.9 333172.0 334089.0 334842.0 336494.3 336882.0 36252.9 36792.0 36858.0 60487.7 61027.8 35055.3 35183.5 35358.8 35393.3 35426.8 15599.0 15819.6 15819.8 15822.0 15847.8 12285.8 12473.1 13164.0 13386.9 13553.7 8138.8 8153.8 8163.2 8233.7 8246.0 3363.6 3386.9 3403.9 3409.9 3415.7 869.7 968.3 1025.7 1053.8 1105.8 30081.1 30127.0 30496.8 30746.0 30798.5 959.6 970.9 1046.8 1074.5 1202.6 14285.4 14545.3 14610.5 14920.1 14959.6 562266.0 626753.0 636190.0 637167.0 642617.0 61533.4 62072.7 62073.7 62075.1 62104.4 1403.5 1464.2 1481.3 1485.2 1505.5 27800.6 29164.6 29426.7 29766.0 29882.1 46408.8 59965.7 61049.0 61200.0 61444.4 470475.0 472890.0 479396.6 479596.0 480319.0 35343.3 35630.8 40295.0 42295.8 46196.0 44321.6 44397.0 45031.1 46459.8 46828.5 36518.3 37096.8 37199.6 37226.6 39073.8 36839.4 36978.0 37006.4 37778.0 37814.2 36715.9 37052.1 37387.8 37769.7 38166.6 31624.0 32014.2 32093.7 32105.8 32388.0 24788.0 24871.5 25048.9 25125.5 25258.5 06:47:09 INFO - PROCESS | 1946 | 06:47:09 INFO - PROCESS | 1946 | [#2] jquery.html Cycles:180 Average:33689.26 Median:2383.90 stddev:99876.19 (4189.6%) stddev-sans-first:100124.96 06:47:09 INFO - PROCESS | 1946 | Values: 330.7 388.7 400.4 401.4 402.6 15892.8 16342.3 19906.1 20465.7 21076.0 16276.9 20123.7 20668.8 21098.1 21126.4 5117.2 5898.6 6067.4 6069.5 6147.7 5985.6 6013.4 6048.5 6058.1 6064.3 4445.0 6026.4 6027.6 6028.7 6034.9 5904.8 5915.9 5937.2 5939.4 5941.9 5577.6 5675.6 5730.0 5746.8 5748.5 13253.9 13470.8 13601.4 13685.0 13702.9 2173.0 2247.4 2268.3 2499.5 2549.6 9215.0 9592.8 9797.2 9898.7 9905.6 1742.8 1755.4 1774.2 1790.5 1797.9 87559.0 116978.4 117266.0 118044.4 119649.2 11268.1 11678.2 11697.2 11712.1 11917.5 13282.5 13852.4 14942.3 14975.3 14994.2 7977.7 7986.0 8092.6 8203.3 8331.6 8646.9 8709.2 8786.8 8876.5 8911.0 4571.2 4573.3 4592.1 4592.9 4652.2 1910.1 1965.5 1966.8 1966.8 1967.7 885.9 967.6 973.3 1039.1 1052.2 19469.7 19660.4 19860.4 20112.0 20388.7 929.4 953.3 979.5 1014.0 1091.6 8868.7 10545.6 10555.5 10589.7 10615.9 589866.0 598229.0 604893.0 605196.0 606014.0 108955.5 114026.0 120385.4 121419.0 123482.4 1010.7 1021.6 1028.5 1038.6 1049.2 17602.0 17667.0 17714.5 17974.6 18214.5 51219.5 51591.5 51872.7 53346.7 53349.5 52594.0 60420.4 63838.4 73858.3 78625.0 7263.3 7673.7 7758.8 7999.2 8138.2 10073.7 11992.5 12031.0 12306.3 12317.9 10234.8 10400.0 10421.3 10485.6 10608.0 9394.6 9450.4 10426.9 10427.3 10496.9 10422.0 10483.8 10670.9 10747.1 10871.5 7844.4 7861.5 8005.3 8073.1 8083.0 7888.3 8400.9 10542.0 10656.2 10691.2 06:47:09 INFO - PROCESS | 1946 | 06:47:09 INFO - PROCESS | 1946 | [#3] mootools.html Cycles:180 Average:5587.69 Median:4876.71 stddev:3137.99 (64.3%) stddev-sans-first:3130.40 06:47:09 INFO - PROCESS | 1946 | Values: 1319.7 1385.0 1512.1 1678.9 1918.8 7823.9 8507.7 8685.2 8687.3 8692.8 8340.2 8631.1 8652.0 8705.2 8747.4 2127.4 2347.0 2396.6 2413.9 2429.2 2424.1 2424.9 2425.0 2425.8 2427.3 2345.6 2378.3 2402.9 2405.0 2405.1 2116.6 2355.8 2403.2 2404.5 2405.9 2381.9 2408.0 2408.3 2410.5 2413.5 8482.5 8540.7 8541.9 8552.3 8571.3 5108.8 5129.8 5228.5 5234.5 5237.4 3436.4 3480.1 3536.3 3553.0 3565.1 1030.5 1036.8 1061.3 1062.0 1071.1 11724.4 11746.4 11764.3 11764.9 11785.9 4208.3 4389.7 4698.2 4735.7 4756.6 9916.6 9962.0 9965.5 9977.2 9978.0 4606.8 5306.5 5316.2 5344.3 5357.7 3655.8 4216.7 4749.5 4786.9 4795.1 3941.2 3969.0 4006.4 4045.2 4074.4 1316.9 1320.8 1321.7 1330.1 1339.6 913.6 925.4 927.2 928.3 932.5 5711.5 5864.6 5884.7 5920.3 5928.3 460.6 463.9 465.3 469.1 481.5 3827.8 3881.4 3927.6 3945.5 3971.6 10246.1 10552.1 10627.8 10679.7 10681.7 10109.7 10545.8 10642.6 10656.3 10677.5 7931.1 8046.3 8186.8 8187.4 8321.3 5540.6 5569.0 5611.7 5631.4 5650.0 7390.7 9326.7 9364.0 9514.0 9521.5 9204.6 9705.0 9722.4 9775.9 9786.1 6579.5 6721.6 7131.1 7455.7 7520.7 8064.2 8092.0 8163.3 8168.9 8203.2 7103.6 7203.1 7224.5 7335.9 7374.2 7110.6 7124.6 7184.6 7230.1 7250.5 6902.3 7029.9 7034.7 7036.4 7038.1 4732.3 4756.4 4783.1 4860.1 4940.3 4863.3 4890.1 4929.9 4994.9 5104.5 06:47:09 INFO - PROCESS | 1946 | 06:47:09 INFO - PROCESS | 1946 | [#4] prototype.html Cycles:180 Average:5733.03 Median:6567.06 stddev:1855.96 (28.3%) stddev-sans-first:1816.16 06:47:09 INFO - PROCESS | 1946 | Values: 320.2 323.5 329.6 332.5 336.5 5124.3 5288.7 6524.5 6539.9 6594.5 6555.4 6559.6 6574.5 6578.8 6581.8 4647.8 4719.9 4756.9 4841.3 4871.7 4774.0 4879.5 4890.2 4896.7 4902.5 4865.1 4867.7 4878.7 4878.8 4881.6 3692.5 4116.0 4669.4 4722.4 4867.5 4181.7 4193.9 4198.7 4203.0 4211.1 5071.2 6109.8 6220.5 6231.8 6240.4 5746.9 5748.5 5755.8 5765.3 5776.9 6985.8 6988.6 6998.0 7000.0 7013.2 4365.4 4541.5 4543.6 4569.0 4587.6 7265.9 7430.2 7437.2 7451.0 7461.0 4626.8 4631.7 4645.7 4646.8 4647.8 4675.9 4681.8 4682.8 4691.6 4701.9 5581.2 5584.7 5591.6 5597.3 5601.7 6765.2 7013.3 7017.0 7028.9 7065.2 1603.4 1621.9 1632.9 1633.3 1636.8 971.1 981.8 1018.8 1021.5 1046.6 6469.0 6607.5 6673.4 6682.2 6685.4 7397.5 7414.1 7419.8 7426.0 7429.6 6664.3 6671.5 6671.8 6679.7 6687.5 3735.2 3829.9 3872.6 3874.2 3894.2 6313.8 7709.6 7748.0 7773.8 7798.3 7489.3 7563.8 7566.4 7582.6 7592.2 7739.9 7759.1 7762.5 7764.7 7765.4 7038.8 7334.2 7339.9 7350.6 7358.9 7164.6 7216.4 7218.6 7219.3 7223.6 7749.1 7759.5 7763.4 7780.5 7786.0 4648.9 4650.6 4650.7 4651.4 4659.2 6357.8 7034.5 7303.3 7306.8 7311.2 5878.3 6502.8 7029.7 7030.4 7056.6 6729.7 6985.6 7005.4 7027.2 7035.6 5612.3 6875.4 6879.9 6888.4 6911.8 6837.6 7000.9 7016.4 7032.4 7120.9 5634.8 6610.2 6758.3 6771.3 6995.9 06:47:09 INFO - PROCESS | 1946 | 06:47:09 INFO - PROCESS | 1946 | [#5] yui.html Cycles:180 Average:17477.35 Median:642.21 stddev:24073.21 (3748.5%) stddev-sans-first:24107.55 06:47:09 INFO - PROCESS | 1946 | Values: 642.6 673.5 722.7 753.8 768.9 14732.3 14867.5 15097.3 15116.2 15122.4 12247.3 13667.2 13879.9 14040.6 14053.4 740.9 752.8 899.8 913.1 939.3 928.5 930.3 934.4 934.8 935.7 966.8 974.5 975.4 975.4 976.2 776.3 956.0 977.9 978.4 981.3 712.9 715.6 716.0 716.2 717.9 14721.3 14782.9 14825.3 14835.3 14879.2 12431.1 12504.8 12731.6 12846.8 13025.0 10017.5 10174.1 10202.8 10244.6 10319.8 3490.2 3507.6 3509.0 3576.9 3581.8 78098.8 79780.4 79860.2 79886.2 79972.0 9252.2 9526.7 9666.6 9692.4 9775.7 22165.3 22183.4 22307.7 22332.7 22356.8 7440.0 7571.2 7642.7 7667.5 7750.7 3548.4 4135.0 4210.7 4269.8 4386.6 3605.8 3671.5 3672.5 3673.3 3676.2 641.8 651.5 657.0 668.1 674.9 520.6 550.4 589.9 600.5 619.4 7766.7 7936.8 7959.5 8033.4 8074.6 570.7 578.2 587.2 592.8 619.1 3594.2 3737.4 3746.7 3840.2 3885.8 79350.0 81427.0 81700.0 82123.8 82463.6 75422.0 75719.0 76472.0 77214.6 77702.0 60289.4 62500.7 65562.7 66163.0 66192.3 6971.9 7475.7 7600.4 7643.2 7688.8 43864.3 47702.8 47815.3 47925.5 49411.8 61305.7 61862.0 62672.0 64547.4 68506.0 8027.0 9737.6 9751.1 9919.0 10171.0 8591.8 10726.0 10993.1 11686.4 12213.8 11242.1 11252.4 11475.5 11550.7 11700.9 11066.9 11169.4 11326.5 11406.4 11863.3 10593.5 10840.5 11039.1 11694.6 11715.8 9248.4 9374.0 9647.6 9668.1 9669.9 9365.2 9427.8 9440.1 9458.7 9477.9 06:47:09 INFO - PROCESS | 1946 | -------- Summary: end -------- 06:47:09 INFO - PROCESS | 1946 | 06:47:09 INFO - TEST-INFO | 1946: exit 0 06:47:09 INFO - TEST-OK | dromaeo_css | took 1114744ms 06:47:09 INFO - TEST-START | kraken 06:47:09 INFO - operating with platform_type : mac_ 06:47:09 INFO - Initialising browser for kraken test... 06:47:09 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmp7VpvPV/profile http://localhost:52119/getInfo.html) 06:47:11 INFO - PROCESS | 1950 | __metrics Screen width/height:1600/1200 06:47:11 INFO - PROCESS | 1950 | colorDepth:24 06:47:11 INFO - PROCESS | 1950 | Browser inner width/height: 1024/689 06:47:11 INFO - PROCESS | 1950 | __metrics 06:47:11 INFO - PROCESS | 1950 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:47:11 ERROR - PROCESS | 1950 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:47:12 INFO - TEST-INFO | 1950: exit 0 06:47:12 INFO - Browser initialized. 06:47:12 INFO - Running cycle 1/1 for kraken test... 06:47:12 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmp7VpvPV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:47:24 INFO - PROCESS | 1952 | RSS: Main: 211382272 06:47:24 INFO - PROCESS | 1952 | 06:47:27 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52119/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52119/tests/kraken/driver.html?testName=audio-beat-detection) 06:47:28 INFO - PROCESS | 1952 | RSS: Main: 271757312 06:47:28 INFO - PROCESS | 1952 | 06:47:30 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52119/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52119/tests/kraken/driver.html?testName=audio-dft) 06:47:31 INFO - PROCESS | 1952 | RSS: Main: 285835264 06:47:31 INFO - PROCESS | 1952 | 06:47:34 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52119/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52119/tests/kraken/driver.html?testName=audio-fft) 06:47:35 INFO - PROCESS | 1952 | RSS: Main: 374476800 06:47:35 INFO - PROCESS | 1952 | 06:47:37 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52119/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52119/tests/kraken/driver.html?testName=audio-oscillator) 06:47:37 INFO - PROCESS | 1952 | RSS: Main: 278433792 06:47:37 INFO - PROCESS | 1952 | 06:47:39 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52119/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52119/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:47:40 INFO - PROCESS | 1952 | RSS: Main: 280670208 06:47:40 INFO - PROCESS | 1952 | 06:47:43 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52119/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52119/tests/kraken/driver.html?testName=imaging-darkroom) 06:47:44 INFO - PROCESS | 1952 | RSS: Main: 318185472 06:47:44 INFO - PROCESS | 1952 | 06:47:47 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52119/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52119/tests/kraken/driver.html?testName=imaging-desaturate) 06:47:47 INFO - PROCESS | 1952 | RSS: Main: 318205952 06:47:47 INFO - PROCESS | 1952 | 06:47:50 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52119/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52119/tests/kraken/driver.html?testName=json-parse-financial) 06:47:51 INFO - PROCESS | 1952 | RSS: Main: 316493824 06:47:51 INFO - PROCESS | 1952 | 06:47:52 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52119/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52119/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:47:53 INFO - PROCESS | 1952 | RSS: Main: 309149696 06:47:53 INFO - PROCESS | 1952 | 06:47:55 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52119/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52119/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:47:55 INFO - PROCESS | 1952 | RSS: Main: 316502016 06:47:55 INFO - PROCESS | 1952 | 06:47:57 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52119/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52119/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:47:58 INFO - PROCESS | 1952 | RSS: Main: 319066112 06:47:58 INFO - PROCESS | 1952 | 06:48:01 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52119/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52119/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:48:02 INFO - PROCESS | 1952 | RSS: Main: 323821568 06:48:02 INFO - PROCESS | 1952 | 06:48:05 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52119/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52119/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:48:05 INFO - PROCESS | 1952 | RSS: Main: 321683456 06:48:05 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52119/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52119/tests/kraken/driver.html?testName=ai-astar) 06:48:07 INFO - PROCESS | 1952 | RSS: Main: 385855488 06:48:07 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | __start_tp_report 06:48:07 INFO - PROCESS | 1952 | _x_x_mozilla_page_load 06:48:07 INFO - PROCESS | 1952 | _x_x_mozilla_page_load_details 06:48:07 INFO - PROCESS | 1952 | |i|pagename|runs| 06:48:07 INFO - PROCESS | 1952 | |0;ai-astar;114;114;119;114;114;112;115;114;114;135 06:48:07 INFO - PROCESS | 1952 | |1;audio-beat-detection;156;122;156;158;122;142;139;120;193;151 06:48:07 INFO - PROCESS | 1952 | |2;audio-dft;185;186;186;185;185;185;181;184;186;185 06:48:07 INFO - PROCESS | 1952 | |3;audio-fft;73;77;75;153;60;64;84;95;155;55 06:48:07 INFO - PROCESS | 1952 | |4;audio-oscillator;96;96;138;76;79;91;120;80;78;135 06:48:07 INFO - PROCESS | 1952 | |5;imaging-gaussian-blur;89;86;95;91;94;91;94;92;94;87 06:48:07 INFO - PROCESS | 1952 | |6;imaging-darkroom;95;96;95;95;95;95;114;98;138;150 06:48:07 INFO - PROCESS | 1952 | |7;imaging-desaturate;87;84;84;85;94;123;82;84;86;85 06:48:07 INFO - PROCESS | 1952 | |8;json-parse-financial;60;65;67;64;63;65;64;84;59;58 06:48:07 INFO - PROCESS | 1952 | |9;json-stringify-tinderbox;63;67;66;71;80;77;71;68;68;69 06:48:07 INFO - PROCESS | 1952 | |10;stanford-crypto-aes;80;81;88;87;96;79;80;80;82;85 06:48:07 INFO - PROCESS | 1952 | |11;stanford-crypto-ccm;130;134;146;144;141;113;143;147;171;140 06:48:07 INFO - PROCESS | 1952 | |12;stanford-crypto-pbkdf2;167;167;166;162;161;165;167;155;203;169 06:48:07 INFO - PROCESS | 1952 | |13;stanford-crypto-sha256-iterative;68;66;70;67;68;67;67;82;90;96 06:48:07 INFO - PROCESS | 1952 | __end_tp_report 06:48:07 INFO - PROCESS | 1952 | __start_cc_report 06:48:07 INFO - PROCESS | 1952 | _x_x_mozilla_cycle_collect,2014 06:48:07 INFO - PROCESS | 1952 | __end_cc_report 06:48:07 INFO - PROCESS | 1952 | __startTimestamp1464270487726__endTimestamp 06:48:07 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | ------- Summary: start ------- 06:48:07 INFO - PROCESS | 1952 | Number of tests: 14 06:48:07 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | [#0] ai-astar Cycles:10 Average:116.50 Median:114.00 stddev:6.74 (5.9%) stddev-sans-first:7.08 06:48:07 INFO - PROCESS | 1952 | Values: 114.0 114.0 119.0 114.0 114.0 112.0 115.0 114.0 114.0 135.0 06:48:07 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | [#1] audio-beat-detection Cycles:10 Average:145.90 Median:153.50 stddev:22.29 (14.5%) stddev-sans-first:23.34 06:48:07 INFO - PROCESS | 1952 | Values: 156.0 122.0 156.0 158.0 122.0 142.0 139.0 120.0 193.0 151.0 06:48:07 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.00 stddev:1.48 (0.8%) stddev-sans-first:1.56 06:48:07 INFO - PROCESS | 1952 | Values: 185.0 186.0 186.0 185.0 185.0 185.0 181.0 184.0 186.0 185.0 06:48:07 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | [#3] audio-fft Cycles:10 Average:89.10 Median:74.00 stddev:36.10 (48.8%) stddev-sans-first:37.82 06:48:07 INFO - PROCESS | 1952 | Values: 73.0 77.0 75.0 153.0 60.0 64.0 84.0 95.0 155.0 55.0 06:48:07 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | [#4] audio-oscillator Cycles:10 Average:98.90 Median:79.50 stddev:23.74 (29.9%) stddev-sans-first:25.15 06:48:07 INFO - PROCESS | 1952 | Values: 96.0 96.0 138.0 76.0 79.0 91.0 120.0 80.0 78.0 135.0 06:48:07 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | [#5] imaging-gaussian-blur Cycles:10 Average:91.30 Median:93.00 stddev:3.13 (3.4%) stddev-sans-first:3.21 06:48:07 INFO - PROCESS | 1952 | Values: 89.0 86.0 95.0 91.0 94.0 91.0 94.0 92.0 94.0 87.0 06:48:07 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | [#6] imaging-darkroom Cycles:10 Average:107.10 Median:95.00 stddev:20.50 (21.6%) stddev-sans-first:21.27 06:48:07 INFO - PROCESS | 1952 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 114.0 98.0 138.0 150.0 06:48:07 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | [#7] imaging-desaturate Cycles:10 Average:89.40 Median:85.00 stddev:12.24 (14.4%) stddev-sans-first:12.95 06:48:07 INFO - PROCESS | 1952 | Values: 87.0 84.0 84.0 85.0 94.0 123.0 82.0 84.0 86.0 85.0 06:48:07 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:64.50 stddev:7.31 (11.3%) stddev-sans-first:7.54 06:48:07 INFO - PROCESS | 1952 | Values: 60.0 65.0 67.0 64.0 63.0 65.0 64.0 84.0 59.0 58.0 06:48:07 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:70.00 stddev:5.10 (7.3%) stddev-sans-first:4.74 06:48:07 INFO - PROCESS | 1952 | Values: 63.0 67.0 66.0 71.0 80.0 77.0 71.0 68.0 68.0 69.0 06:48:07 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:83.50 stddev:5.33 (6.4%) stddev-sans-first:5.47 06:48:07 INFO - PROCESS | 1952 | Values: 80.0 81.0 88.0 87.0 96.0 79.0 80.0 80.0 82.0 85.0 06:48:07 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | [#11] stanford-crypto-ccm Cycles:10 Average:140.90 Median:143.50 stddev:14.64 (10.2%) stddev-sans-first:14.99 06:48:07 INFO - PROCESS | 1952 | Values: 130.0 134.0 146.0 144.0 141.0 113.0 143.0 147.0 171.0 140.0 06:48:07 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.20 Median:167.00 stddev:12.89 (7.7%) stddev-sans-first:13.67 06:48:07 INFO - PROCESS | 1952 | Values: 167.0 167.0 166.0 162.0 161.0 165.0 167.0 155.0 203.0 169.0 06:48:07 INFO - PROCESS | 1952 | 06:48:07 INFO - PROCESS | 1952 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:69.00 stddev:11.07 (16.0%) stddev-sans-first:11.52 06:48:07 INFO - PROCESS | 1952 | Values: 68.0 66.0 70.0 67.0 68.0 67.0 67.0 82.0 90.0 96.0 06:48:07 INFO - PROCESS | 1952 | -------- Summary: end -------- 06:48:07 INFO - PROCESS | 1952 | 06:48:08 INFO - TEST-INFO | 1952: exit 0 06:48:08 INFO - TEST-OK | kraken | took 58907ms 06:48:08 INFO - SUITE-END | took 1173s 06:48:08 INFO - Completed test suite (00:19:33) 06:48:08 INFO - javascript benchmark 06:48:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3313.884014059508, 3399.0, 3409.9147521311957, 3432.5709286339206, 3475.1138459692775, 5532.889342213159, 5537.0, 5538.556915446765, 5557.805476808313, 5576.832695019151, 5491.258680078198, 5516.586256030803, 5566.2207290979195, 5571.888562228753, 5650.0677388231, 4957.132501812186, 5054.646174767777, 5059.822906198284, 5134.845954621349, 5188.247704082905, 5086.313347698069, 5171.534561889429, 5172.120739474299, 5182.093133701606, 5195.298634684307, 5109.923351149736, 5125.820596279131, 5136.871578210537, 5165.845024649267, 5173.767180476888, 5138.511841375082, 5177.404598471187, 5194.220866869962, 5200.687958722451, 5239.921401178985, 5033.395992480889, 5041.218611115283, 5100.69395836251, 5116.411612664554, 5118.232265160219, 5439.048166570874, 5439.864003399907, 5447.373552041527, 5499.312585926759, 5515.420880807537, 6047.78832740852, 6057.0, 6085.208922840003, 6088.847778805521, 6105.9694701526205, 5118.283440318359, 5969.880602387928, 6092.66490343031, 6115.235595550556, 6199.473044791198, 4806.735629540409, 5545.306836645419, 5614.578906581982, 5621.578381621394, 5649.915251271234, 6256.843578910563, 6274.881990059746, 6292.716827742763, 6339.144215530844, 6339.461145802658, 5809.070548712186, 5911.76352945886, 5950.732217050243, 6003.339632640406, 6026.035834266584, 5138.2806407103035, 6039.335673075958, 6079.513638908877, 6081.939180608226, 6116.510679145657, 5065.746712664352, 5985.102234664835, 6005.729742161613, 6017.127516510169, 6020.096985452217, 5900.1739756434135, 5989.341172471025, 6028.9397106029255, 6045.6070355426755, 6075.817725468155, 4742.5731684148595, 4794.640401969867, 4812.590929771009, 4814.229723244334, 4823.589994850477, 4344.326629372453, 4360.716553424018, 4365.170617582713, 4389.385486031958, 4394.472663280363, 5534.086875665551, 5588.245586845724, 5588.552915766729, 5596.384397716248, 5620.831375058755, 6105.8168254952425, 6126.754929802847, 6150.692465376803, 6166.167567378347, 6184.165137706442, 5429.864253393696, 5568.972155139199, 5577.107662774018, 5577.581681373913, 5591.049521581341, 5223.738813059332, 5245.606579506476, 5251.212318152231, 5281.973590132025, 5291.444398338119, 6306.527010474325, 6334.239891212992, 6343.524235682342, 6343.524235682342, 6350.682465876687, 5964.701764911824, 6035.064564992434, 6098.0, 6113.786017489367, 6116.0, 5224.764885580082, 6264.373562643699, 6338.429541341301, 6342.873142537216, 6390.297067322498, 6045.78839740607, 6064.818055458343, 6068.605540639845, 6070.0, 6071.726772295346, 5967.0, 6162.876742465215, 6169.81490555284, 6175.042868355412, 6197.628142311476, 4913.213885778258, 5851.882962340645, 5861.0, 5892.646441213541, 5920.674362910081, 4489.618382437529, 5863.794767183128, 5867.882642347215, 5878.470937615635, 5879.176915231786, 5536.809585939042, 5587.888242235052, 5723.656580605177, 5740.598158128891, 5772.451617096369, 5881.5588830837005, 5891.793787217598, 5894.145348924468, 5928.8814223716145, 5933.050711886078, 4818.71087734737, 5792.565557583114, 5794.985877471341, 5800.1299805029585, 5806.970965145148], "value": 5536.943255327198, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.4996903158778, 491.6288202407183, 522.5401646551037, 530.5384315645389, 584.1821449970045, 30805.537916931236, 31564.368712625735, 38358.0, 38924.416133758015, 38989.80505097478, 41953.58046419535, 42365.0, 42369.364459533135, 42384.78807605965, 42530.0, 8416.957915210416, 9371.062893710625, 9587.185089267387, 9590.992945740702, 9607.66373176939, 8577.313814894824, 9740.318177727562, 11642.476088576035, 11674.883251167506, 11675.24110932791, 11542.538298468051, 11613.6515904523, 11621.302721836717, 11662.533498660043, 11667.299962002264, 8535.103814099555, 8722.781930451789, 8725.127487251224, 11434.42827858608, 11588.072954163647, 11344.0, 11558.653240402802, 11572.6528204154, 11574.479148438257, 11576.0, 38416.42375364344, 38982.610173898465, 38991.0, 39028.804855975824, 39072.413913791315, 3328.151321413024, 3340.46552551593, 3533.0, 3599.406097993828, 3712.053426376257, 26163.43019418841, 26894.32764180911, 28713.71286287152, 28962.855185723936, 29108.70891291081, 2443.3402981195154, 2731.9726802731916, 2737.137801592492, 2744.862756862149, 2897.913062608125, 333172.0, 334089.0, 334841.99999999756, 336494.3175284108, 336882.0, 36252.918501927634, 36792.0, 36858.0, 60487.69756151191, 61027.779444410866, 35055.29889402249, 35183.4722479163, 35358.823205883804, 35393.29213415717, 35426.822865886024, 15599.0, 15819.604509887115, 15819.841801581837, 15822.0, 15847.762283565755, 12285.815712764315, 12473.064520161024, 13164.0, 13386.866131338757, 13553.661158471117, 8138.83722325562, 8153.796155096052, 8163.22449367321, 8233.670653173805, 8245.95877020611, 3363.613184483767, 3386.8983930482127, 3403.9447771190517, 3409.948850767191, 3415.7267418606452, 869.7303835810792, 968.2883080935507, 1025.6512785652915, 1053.8189806156672, 1105.8175401058736, 30081.097567073022, 30127.0, 30496.847515762278, 30746.0, 30798.53802192969, 959.5825815770162, 970.9077637624414, 1046.8429735539578, 1074.4573990134932, 1202.6211743301008, 14285.42858285657, 14545.34545945435, 14610.465901080446, 14920.104793712411, 14959.626009349853, 562266.0, 626753.0, 636190.0, 637167.0, 642617.0, 61533.38466615366, 62072.68963655243, 62073.689631551555, 62075.068873966025, 62104.37895621076, 1403.5368328451586, 1464.209326963449, 1481.2593703148425, 1485.1831492678768, 1505.4580351073823, 27800.58299125474, 29164.562531562467, 29426.705732943254, 29765.958191463196, 29882.103536893927, 46408.76795616, 59965.70017150061, 61049.0, 61200.0, 61444.38555614387, 470475.0, 472890.0, 479396.60301698267, 479596.0, 480319.0, 35343.29313413769, 35630.821845890605, 40295.0, 42295.7885210572, 46196.0, 44321.55678443174, 44397.0, 45031.099378012914, 46459.76770116263, 46828.53171468378, 36518.26963460663, 37096.814515927246, 37199.62800371962, 37226.627733722315, 39073.804630976665, 36839.44740828943, 36978.0, 37006.39075015085, 37778.0, 37814.2437151261, 36715.89852304435, 37052.073698157765, 37387.813060934524, 37769.678061267725, 38166.6183338163, 31624.0, 32014.199645009063, 32093.679063210002, 32105.75921424637, 32388.0, 24788.008479660613, 24871.502569948138, 25048.874755626835, 25125.497490050464, 25258.4844909306], "value": 18640.69174294057, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.6924560159051, 388.69099066242336, 400.4493820996129, 401.3898873711958, 402.61147992187534, 15892.761608575851, 16342.34630614774, 19906.10422530986, 20465.693014604774, 21076.0, 16276.918615406936, 20123.698144527807, 20668.793312066875, 21098.050587723552, 21126.366209013715, 5117.181250999841, 5898.557608179382, 6067.393260673942, 6069.453749162574, 6147.661878596676, 5985.6109352892145, 6013.398660133974, 6048.485878700316, 6058.061000544901, 6064.3026052003925, 4444.97777511112, 6026.367231440701, 6027.578069535134, 6028.668423236721, 6034.939650603504, 5904.793332233373, 5915.852103697377, 5937.168796368512, 5939.376365481606, 5941.851453713649, 5577.637453565546, 5675.574331925122, 5729.997250481149, 5746.798862039808, 5748.45389687979, 13253.933730331382, 13470.797938030937, 13601.387937542835, 13685.0, 13702.93148534261, 2172.9565408691738, 2247.370736193868, 2268.3081660093667, 2499.487605040962, 2549.6175573663913, 9215.03242159577, 9592.808143837085, 9797.167240784542, 9898.653547125816, 9905.554250058765, 1742.8170042145518, 1755.4119370011063, 1774.1661419132986, 1790.534461040126, 1797.8561715062763, 87559.0, 116978.41510792477, 117266.0, 118044.40977795141, 119649.20526192115, 11268.09855211581, 11678.189880568525, 11697.23967942098, 11712.063034957184, 11917.523299068114, 13282.53511127106, 13852.376643051088, 14942.25288735559, 14975.32611032506, 14994.175320357266, 7977.683682192373, 7986.0, 8092.554909480035, 8203.343732501386, 8331.625076871554, 8646.913530864736, 8709.216170544554, 8786.824263514694, 8876.51179185138, 8911.0, 4571.2000399929875, 4573.262160379035, 4592.10453961481, 4592.94362296667, 4652.162610730101, 1910.1308904448442, 1965.518447980254, 1966.7738210105736, 1966.783653798081, 1967.685170372762, 885.8715486254457, 967.6419724701906, 973.3235401396104, 1039.0804138337626, 1052.2423854824515, 19469.707954380694, 19660.410187694393, 19860.40418787439, 20112.0, 20388.694169587467, 929.3726734454351, 953.3136141977765, 979.4661909259471, 1014.031599822168, 1091.6015654286286, 8868.733937981871, 10545.578176872992, 10555.525001374957, 10589.735256618493, 10615.946920265504, 589866.0, 598229.0, 604893.0, 605196.0, 606014.0, 108955.45522272338, 114026.0, 120385.39807300907, 121419.0, 123482.38258808828, 1010.7018429563296, 1021.6219998600566, 1028.5165971993151, 1038.5949479702922, 1049.2183323423944, 17602.0, 17667.0, 17714.468565943043, 17974.640507190044, 18214.45356639303, 51219.48780512147, 51591.48408515867, 51872.703182420744, 53346.66633334198, 53349.46650533445, 52594.0, 60420.39579604324, 63838.3616163851, 73858.26141738729, 78625.0, 7263.309985551364, 7673.731419400294, 7758.806029849073, 7999.200079991954, 8138.226868447488, 10073.748156296151, 11992.460339284578, 12031.0, 12306.323152226714, 12317.938410307892, 10234.795304094025, 10400.011998860096, 10421.322614030063, 10485.633002844865, 10608.0, 9394.637777522357, 9450.385724927857, 10426.895731042592, 10427.270091093551, 10496.947515262375, 10422.0, 10483.790324193626, 10670.946645267035, 10747.086497647788, 10871.456427178451, 7844.372450204084, 7861.489003214772, 8005.319547838499, 8073.111957684768, 8082.959585202037, 7888.2506161914525, 8400.915990840012, 10541.998510141524, 10656.163781038666, 10691.198160137865], "value": 9173.67346424512, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1319.722858199778, 1384.9958779884578, 1512.1003003213084, 1678.8992660440367, 1918.7985261547528, 7823.9217607823975, 8507.702230421937, 8685.174908383702, 8687.305015598753, 8692.826143477143, 8340.165983401657, 8631.093735157796, 8651.961764588243, 8705.216530512253, 8747.387682862201, 2127.361791462555, 2346.9647955280684, 2396.6405039244123, 2413.9034438622434, 2429.2226487524, 2424.066734307287, 2424.9393765155837, 2425.042108367194, 2425.842320249178, 2427.3203503019095, 2345.5895218336723, 2378.3221781792195, 2402.9158979435724, 2404.95190096198, 2405.1461731085474, 2116.5978464091772, 2355.7644235576477, 2403.2429784617875, 2404.495056038234, 2405.9398515037087, 2381.89281482332, 2407.9759202408104, 2408.3256688127353, 2410.4937963027787, 2413.4931664350506, 8482.53346065966, 8540.74377768668, 8541.871871921927, 8552.27305679018, 8571.271441927382, 5108.846734597968, 5129.820456284012, 5228.503292187274, 5234.502722241381, 5237.423883372826, 3436.432988556885, 3480.112571294329, 3536.3104194682046, 3553.0, 3565.144365352323, 1030.4697524176593, 1036.7926414717012, 1061.2571200159919, 1062.0388548363744, 1071.0521188748014, 11724.413779311086, 11746.412679365998, 11764.2941423514, 11764.882351176464, 11785.941070294593, 4208.284591619432, 4389.736615803062, 4698.1778188816825, 4735.739534325645, 4756.619470442356, 9916.553755081039, 9962.0, 9965.501724913725, 9977.201823854219, 9978.002199779963, 4606.838760643362, 5306.52241298285, 5316.202569614511, 5344.331958505187, 5357.678539287655, 3655.8354874030733, 4216.662666986634, 4749.525047495292, 4786.856394308176, 4795.112904112751, 3941.231459865356, 3968.9603103969166, 4006.3790112532606, 4045.211183819186, 4074.3684728867074, 1316.8748968847945, 1320.7622627926874, 1321.717933604402, 1330.1221194011905, 1339.6316013096282, 913.5614904845578, 925.4169872979979, 927.2350310993457, 928.3223247029571, 932.4964519159578, 5711.457411545896, 5864.618799778001, 5884.705764711747, 5920.259967504062, 5928.318243401977, 460.580871407018, 463.9141758774638, 465.28346346626495, 469.07592043673907, 481.5040508276481, 3827.827747751358, 3881.437191607201, 3927.626875446828, 3945.506811648544, 3971.6425521703186, 10246.129079028216, 10552.103071238877, 10627.84058239127, 10679.73300667474, 10681.679549613524, 10109.747256318651, 10545.789084218273, 10642.627508037336, 10656.307340022873, 10677.466126693635, 7931.127575966639, 8046.316063134582, 8186.7953301167945, 8187.38594605407, 8321.292690121287, 5540.63985840919, 5568.972155139199, 5611.7194140292, 5631.380548139783, 5649.9830030594885, 7390.704371825282, 9326.673566425143, 9364.016778238187, 9513.952430237805, 9521.523923803643, 9204.585793639437, 9704.980976997596, 9722.416655000723, 9775.902240977784, 9786.119249267314, 6579.539432239697, 6721.596704197764, 7131.108611423572, 7455.739049133254, 7520.661570229244, 8064.233897779702, 8091.959540202261, 8163.346932245525, 8168.918310816815, 8203.179682031749, 7103.573785572882, 7203.099612548432, 7224.458165637493, 7335.889961650473, 7374.225706300761, 7110.608916509642, 7124.5725256484775, 7184.640767961477, 7230.060092187982, 7250.528715633467, 6902.344277293649, 7029.894551581628, 7034.718611255493, 7036.366726994595, 7038.085048943604, 4732.313814496947, 4756.429228492603, 4783.115123701987, 4860.076585448754, 4940.258961155711, 4863.294822250683, 4890.119778439915, 4929.9014019720125, 4994.875128121826, 5104.515071068243], "value": 4441.678146565513, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.247418566369, 323.47758370232066, 329.64398449674354, 332.4763497491453, 336.4969370790668, 5124.282600435938, 5288.735563221833, 6524.510661700374, 6539.901901471476, 6594.472442204624, 6555.41001309882, 6559.573627714195, 6574.539782215247, 6578.835529111775, 6581.7696380626685, 4647.814087436498, 4719.858404247861, 4756.857294281177, 4841.3222148899, 4871.683340582851, 4774.0, 4879.512048795127, 4890.21756518957, 4896.706197628154, 4902.46072931979, 4865.075635629237, 4867.707937523742, 4878.70727756334, 4878.829240976566, 4881.585065269456, 3692.5384326958997, 4115.9588404115875, 4669.392978912737, 4722.409698787652, 4867.51324867514, 4181.686373521968, 4193.874183774492, 4198.6641068714425, 4202.957970420318, 4211.05251318454, 5071.1632580624155, 6109.816705498843, 6220.502359811204, 6231.844203894893, 6240.375962403723, 5746.913796293059, 5748.482636562688, 5755.769769209227, 5765.308163020422, 5776.913346299809, 6985.79042628722, 6988.61562614056, 6997.965010174917, 7000.019997200396, 7013.193482749498, 4365.43249377582, 4541.545845415465, 4543.636509079266, 4569.0, 4587.564181402761, 7265.8546829063125, 7430.21059259776, 7437.181909989897, 7450.962745186293, 7461.045645807676, 4626.8149274028665, 4631.698939568917, 4645.6980296280635, 4646.83736069236, 4647.837325693585, 4675.906481870344, 4681.836135735233, 4682.765861706969, 4691.554302341272, 4701.929471057937, 5581.190727344512, 5584.720763961867, 5591.608587398924, 5597.328320601554, 5601.747921343549, 6765.188177418741, 7013.333733295329, 7017.0, 7028.92971070293, 7065.2228254891925, 1603.3907115296113, 1621.9432319868984, 1632.8856980011285, 1633.3058450158635, 1636.8363163683537, 971.1453920550013, 981.8183636027358, 1018.7504061504978, 1021.5249908792299, 1046.638909576195, 6468.967655161694, 6607.471402287805, 6673.366030227121, 6682.231543372477, 6685.364890335411, 7397.482176247719, 7414.110306763115, 7419.777406677808, 7426.0, 7429.62851857405, 6664.333566643394, 6671.532992690562, 6671.799846004627, 6679.666016699146, 6687.531872768859, 3735.234276973214, 3829.885103446901, 3872.6127387261613, 3874.2057878134915, 3894.2211557688574, 6313.810585682437, 7709.575973321521, 7748.0, 7773.844523109507, 7798.33714134305, 7489.251074892467, 7563.773086807405, 7566.35685966688, 7582.62086895653, 7592.240775922474, 7739.92260077392, 7759.146493885668, 7762.456628035983, 7764.728234511765, 7765.417593680497, 7038.788836334918, 7334.229905860021, 7339.8532029360185, 7350.558966462029, 7358.889616655647, 7164.605946672983, 7216.3505284524645, 7218.639068046472, 7219.278072192738, 7223.627510772936, 7749.147593764794, 7759.495632783851, 7763.378929685725, 7780.533168009937, 7786.0, 4648.907021859612, 4650.627949764078, 4650.697704649187, 4651.395318608559, 4659.184642687448, 6357.809265722036, 7034.47241456903, 7303.342699157101, 7306.780796576111, 7311.159216689936, 5878.294604647299, 6502.804915852532, 7029.683664235224, 7030.402415794715, 7056.6118863463, 6729.730810767514, 6985.615791131537, 7005.439564834903, 7027.227005029545, 7035.613041282574, 5612.326520817528, 6875.3812156906115, 6879.862402752017, 6888.41448476885, 6911.7926462206215, 6837.555558888656, 7000.929990700231, 7016.438684905093, 7032.367086962198, 7120.857582848417, 5634.802781902614, 6610.2398224204835, 6758.324167583202, 6771.322867713189, 6995.930040699528], "value": 5111.108536162924, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [642.5791106825028, 673.5184343194614, 722.7073035420657, 753.8266198774281, 768.9269519395659, 14732.263386830657, 14867.479638212664, 15097.320620572073, 15116.244187790622, 15122.395104195846, 12247.32639704818, 13667.248301343423, 13879.930600346985, 14040.578782636512, 14053.437862485489, 740.870347689155, 752.8494301139766, 899.815537814748, 913.0595486648763, 939.286142531674, 928.5264515097315, 930.2790337488433, 934.4159900062461, 934.8036912248436, 935.7333160049387, 966.84530475124, 974.507873523873, 975.4293738163183, 975.4439969217549, 976.2043934193609, 776.343989329016, 955.9962039858156, 977.9217662586976, 978.3542861711272, 981.259149342242, 712.9001939728442, 715.5563550598662, 715.9821004474878, 716.1763971432841, 717.9246179151166, 14721.337539810735, 14782.852171478362, 14825.332860021217, 14835.332410041574, 14879.18164500952, 12431.067669924701, 12504.842410576779, 12731.61805145847, 12846.807297890538, 13025.0, 10017.549210285555, 10174.135198508136, 10202.846957295647, 10244.641437549724, 10319.793604127875, 3490.214701692099, 3507.5966263879727, 3509.0350153707936, 3576.928461430783, 3581.83881725323, 78098.82851757228, 79780.40439191184, 79860.20139798586, 79886.20113798903, 79972.0, 9252.213561847318, 9526.714198574055, 9666.565004574812, 9692.369995950241, 9775.657851975147, 22165.335039948826, 22183.44541386446, 22307.665385019238, 22332.665010024863, 22356.77643223547, 7440.0, 7571.205023472567, 7642.69429222836, 7667.53994760305, 7750.689972401154, 3548.4499902514635, 4135.048938744046, 4210.7473551587, 4269.807858646368, 4386.627136693353, 3605.8197090145386, 3671.541057367829, 3672.5042119313553, 3673.3204357194004, 3676.2279921216846, 641.8491654461197, 651.5439192565224, 656.9605823650596, 668.0913957018452, 674.9392554670005, 520.6121439527501, 550.3725752641991, 589.9174115623816, 600.5015836855399, 619.3775255867824, 7766.689332426753, 7936.761897143095, 7959.482633628795, 8033.437659363788, 8074.596270186585, 570.6918264137403, 578.1789858401081, 587.2483221476517, 592.8369698332907, 619.0744836469507, 3594.191306955914, 3737.4206997915367, 3746.662800347982, 3840.1551658635058, 3885.786281754474, 79350.0, 81427.0, 81700.0, 82123.7681434779, 82463.58768206241, 75422.0, 75719.0, 76472.0, 77214.61392693112, 77702.0, 60289.39710602925, 62500.68749656223, 65562.68874622481, 66163.0, 66192.33807661958, 6971.895421568782, 7475.738349157753, 7600.3539699123985, 7643.235676432312, 7688.769336919901, 43864.34203486887, 47702.76148619373, 47815.28277075777, 47925.5207447935, 49411.75294123506, 61305.69347153236, 61862.0, 62672.0, 64547.35452645413, 68506.0, 8027.0, 9737.610495580097, 9751.122398984126, 9919.0, 10171.0, 8591.790222275238, 10726.0, 10993.065589424989, 11686.415679215836, 12213.75572488563, 11242.10063194926, 11252.437378130897, 11475.48360323804, 11550.653480395602, 11700.941495292469, 11066.889331106586, 11169.38568378747, 11326.490307935997, 11406.429678516208, 11863.34751588671, 10593.52329145175, 10840.5121769519, 11039.061679757311, 11694.649160525198, 11715.778161005792, 9248.352615316864, 9374.0, 9647.565859536197, 9668.081532254424, 9669.854952175863, 9365.157135857806, 9427.764305892408, 9440.055994400505, 9458.716238512856, 9477.905220947978], "value": 6772.847801932059, "unit": "score"}], "name": "dromaeo_css", "value": 7253.109918273058, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 119.0, 114.0, 114.0, 112.0, 115.0, 114.0, 114.0, 135.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 122.0, 156.0, 158.0, 122.0, 142.0, 139.0, 120.0, 193.0, 151.0], "value": 145.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 186.0, 185.0, 185.0, 185.0, 181.0, 184.0, 186.0, 185.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 77.0, 75.0, 153.0, 60.0, 64.0, 84.0, 95.0, 155.0, 55.0], "value": 89.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 138.0, 76.0, 79.0, 91.0, 120.0, 80.0, 78.0, 135.0], "value": 98.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 95.0, 91.0, 94.0, 91.0, 94.0, 92.0, 94.0, 87.0], "value": 91.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 114.0, 98.0, 138.0, 150.0], "value": 107.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 84.0, 85.0, 94.0, 123.0, 82.0, 84.0, 86.0, 85.0], "value": 89.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 65.0, 67.0, 64.0, 63.0, 65.0, 64.0, 84.0, 59.0, 58.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 66.0, 71.0, 80.0, 77.0, 71.0, 68.0, 68.0, 69.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 88.0, 87.0, 96.0, 79.0, 80.0, 80.0, 82.0, 85.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 134.0, 146.0, 144.0, 141.0, 113.0, 143.0, 147.0, 171.0, 140.0], "value": 140.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 166.0, 162.0, 161.0, 165.0, 167.0, 155.0, 203.0, 169.0], "value": 168.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 70.0, 67.0, 68.0, 67.0, 67.0, 82.0, 90.0, 96.0], "value": 74.1, "unit": "score"}], "name": "kraken", "value": 1524.8999999999999, "alertThreshold": 2.0}]} 06:48:08 INFO - Return code: 0 06:48:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:48:08 ERROR - # TBPL SUCCESS # 06:48:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:48:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:48:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:48:09 INFO - 2016-05-26 06:48:09.009 screenresolution[1956:17211] starting screenresolution argv=screenresolution get 06:48:09 INFO - 2016-05-26 06:48:09.012 screenresolution[1956:17211] Display 0: 1600x1200x32@60 06:48:09 INFO - 2016-05-26 06:48:09.017 screenresolution[1957:17214] starting screenresolution argv=screenresolution list 06:48:09 INFO - Available Modes on Display 0 06:48:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:48:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:48:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:48:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:48:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:48:09 INFO - Intel Iris: 06:48:09 INFO - Chipset Model: Intel Iris 06:48:09 INFO - Type: GPU 06:48:09 INFO - Bus: Built-In 06:48:09 INFO - VRAM (Dynamic, Max): 1536 MB 06:48:09 INFO - Vendor: Intel (0x8086) 06:48:09 INFO - Device ID: 0x0a2e 06:48:09 INFO - Revision ID: 0x0009 06:48:09 INFO - Displays: 06:48:09 INFO - Display: 06:48:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:48:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:48:09 INFO - Display Serial Number: 1600x1200 60 06:48:09 INFO - Main Display: Yes 06:48:09 INFO - Mirror: Off 06:48:09 INFO - Online: Yes 06:48:09 INFO - Rotation: Supported 06:48:09 INFO - Return code: 0 06:48:09 INFO - Running post-action listener: _resource_record_post_action 06:48:09 INFO - Running post-run listener: _resource_record_post_run 06:48:10 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 413696; Write bytes: 651036160; Read time: 6; Write time: 1851 06:48:10 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 350861824; Write bytes: 347251200; Read time: 27474; Write time: 692 06:48:10 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 413696; Write bytes: 292996096; Read time: 6; Write time: 1127 06:48:10 INFO - Running post-run listener: _upload_blobber_files 06:48:10 INFO - Blob upload gear active. 06:48:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:48:10 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:48:10 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:48:10 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:48:10 INFO - (blobuploader) - INFO - Open directory for files ... 06:48:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:48:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:48:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:48:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:48:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:48:12 INFO - (blobuploader) - INFO - Done attempting. 06:48:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:48:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:48:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:48:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:48:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:48:13 INFO - (blobuploader) - INFO - Done attempting. 06:48:13 INFO - (blobuploader) - INFO - Iteration through files over. 06:48:13 INFO - Return code: 0 06:48:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:48:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:48:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88fbb844f49c038befa5f60be252be3644bb113e288912e1e77cb8999610d2c75db08f33732b749a426007690d568c26b63e1b1215367e525f1040e51a7e7c8b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d33d68c695c4567511a930403e9d3d5114dbd5bd0cbae1413ac8d30cde507e13d1078cc2c3539aa286a040321c4fedaf9595a34faa3ac97c8b8d9e775d6dda33"} 06:48:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:48:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:48:13 INFO - Contents: 06:48:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88fbb844f49c038befa5f60be252be3644bb113e288912e1e77cb8999610d2c75db08f33732b749a426007690d568c26b63e1b1215367e525f1040e51a7e7c8b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d33d68c695c4567511a930403e9d3d5114dbd5bd0cbae1413ac8d30cde507e13d1078cc2c3539aa286a040321c4fedaf9595a34faa3ac97c8b8d9e775d6dda33"} 06:48:13 INFO - Running post-run listener: copy_logs_to_upload_dir 06:48:13 INFO - Copying logs to upload dir... 06:48:13 INFO - mkdir: /builds/slave/test/build/upload/logs 06:48:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.896545 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-26 06:48:13.323961) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 06:48:13.328006) ========= 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.UMOdv6bOau/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.aotvLwAaYc/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88fbb844f49c038befa5f60be252be3644bb113e288912e1e77cb8999610d2c75db08f33732b749a426007690d568c26b63e1b1215367e525f1040e51a7e7c8b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d33d68c695c4567511a930403e9d3d5114dbd5bd0cbae1413ac8d30cde507e13d1078cc2c3539aa286a040321c4fedaf9595a34faa3ac97c8b8d9e775d6dda33"} 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.011183 build_url: 'https://queue.taskcluster.net/v1/task/C14b095uRmedJWLAaldqCw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88fbb844f49c038befa5f60be252be3644bb113e288912e1e77cb8999610d2c75db08f33732b749a426007690d568c26b63e1b1215367e525f1040e51a7e7c8b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d33d68c695c4567511a930403e9d3d5114dbd5bd0cbae1413ac8d30cde507e13d1078cc2c3539aa286a040321c4fedaf9595a34faa3ac97c8b8d9e775d6dda33"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 06:48:13.364044) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 06:48:13.364507) ========= 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.UMOdv6bOau/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.aotvLwAaYc/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.005170 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 06:48:13.415448) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 06:48:13.415918) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 06:48:13.416450) ========= ========= Total master_lag: 0.18 =========