builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0159 starttime: 1489774023.44 results: success (0) buildid: 20170317090120 builduid: d5f9bc6fb65543dda1fecf1f52e7de66 revision: bc5fff53646952aad7049f86e0340f919a8b5719 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:03.440376) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:03.440768) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:03.441066) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:03.467895) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:03.468192) ========= 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.NLAkXL4yuk/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.LgejRIgenJ/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 --2017-03-17 11:07:03-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.40M=0.001s 2017-03-17 11:07:03 (9.40 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091192 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:03.584235) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:03.584858) ========= 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.NLAkXL4yuk/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.LgejRIgenJ/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.028149 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:03.635163) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-17 11:07:03.635516) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev bc5fff53646952aad7049f86e0340f919a8b5719 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev bc5fff53646952aad7049f86e0340f919a8b5719 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NLAkXL4yuk/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.LgejRIgenJ/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 2017-03-17 11:07:03,737 truncating revision to first 12 chars 2017-03-17 11:07:03,737 Setting DEBUG logging. 2017-03-17 11:07:03,737 attempt 1/10 2017-03-17 11:07:03,737 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/bc5fff536469?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-17 11:07:06,236 unpacking tar archive at: pine-bc5fff536469/testing/mozharness/ program finished with exit code 0 elapsedTime=2.759909 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-17 11:07:06.418947) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:06.419264) ========= script_repo_revision: bc5fff53646952aad7049f86e0340f919a8b5719 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:06.419792) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:06.420057) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-17 11:07:06.440179) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-17 11:07:06.440578) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NLAkXL4yuk/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.LgejRIgenJ/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 11:07:06 INFO - MultiFileLogger online at 20170317 11:07:06 in /builds/slave/test 11:07:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 11:07:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:07:06 INFO - {'append_to_log': False, 11:07:06 INFO - 'base_work_dir': '/builds/slave/test', 11:07:06 INFO - 'blob_upload_branch': 'Pine', 11:07:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:07:06 INFO - 'branch': 'Pine', 11:07:06 INFO - 'buildbot_json_path': 'buildprops.json', 11:07:06 INFO - 'config_files': ('talos/mac_config.py',), 11:07:06 INFO - 'default_actions': ('clobber', 11:07:06 INFO - 'read-buildbot-config', 11:07:06 INFO - 'download-and-extract', 11:07:06 INFO - 'populate-webroot', 11:07:06 INFO - 'create-virtualenv', 11:07:06 INFO - 'install', 11:07:06 INFO - 'run-tests'), 11:07:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:07:06 INFO - 'download_minidump_stackwalk': True, 11:07:06 INFO - 'download_symbols': 'ondemand', 11:07:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:07:06 INFO - 'tooltool.py': '/tools/tooltool.py', 11:07:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:07:06 INFO - '/tools/misc-python/virtualenv.py')}, 11:07:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:07:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:07:06 INFO - 'gecko_profile': False, 11:07:06 INFO - 'gecko_profile_interval': 0, 11:07:06 INFO - 'installer_path': 'installer.exe', 11:07:06 INFO - 'log_level': 'info', 11:07:06 INFO - 'log_name': 'talos', 11:07:06 INFO - 'log_to_console': True, 11:07:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:07:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:07:06 INFO - 'opt_config_files': (), 11:07:06 INFO - 'pip_index': False, 11:07:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:07:06 INFO - 'cmd': ('bash', 11:07:06 INFO - '-c', 11:07:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:07:06 INFO - 'enabled': True, 11:07:06 INFO - 'halt_on_failure': False, 11:07:06 INFO - 'name': 'check_screen_resolution'},), 11:07:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:07:06 INFO - 'cmd': ('bash', 11:07:06 INFO - '-c', 11:07:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:07:06 INFO - 'enabled': True, 11:07:06 INFO - 'halt_on_failure': False, 11:07:06 INFO - 'name': 'check_screen_resolution'},), 11:07:06 INFO - 'run_cmd_checks_enabled': True, 11:07:06 INFO - 'suite': 'dromaeojs', 11:07:06 INFO - 'system_bits': '32', 11:07:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:07:06 INFO - 'title': 't-yosemite-r7-0159', 11:07:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:07:06 INFO - 'use_talos_json': True, 11:07:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:07:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:07:06 INFO - 'work_dir': 'build'} 11:07:06 INFO - [mozharness: 2017-03-17 18:07:06.657061Z] Running clobber step. 11:07:06 INFO - Running pre-action listener: _resource_record_pre_action 11:07:06 INFO - Running main action method: clobber 11:07:06 INFO - rmtree: /builds/slave/test/build 11:07:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:07:09 INFO - Running post-action listener: _resource_record_post_action 11:07:09 INFO - [mozharness: 2017-03-17 18:07:09.262402Z] Finished clobber step (success) 11:07:09 INFO - [mozharness: 2017-03-17 18:07:09.262533Z] Running read-buildbot-config step. 11:07:09 INFO - Running pre-action listener: _resource_record_pre_action 11:07:09 INFO - Running main action method: read_buildbot_config 11:07:09 INFO - Using buildbot properties: 11:07:09 INFO - { 11:07:09 INFO - "project": "", 11:07:09 INFO - "product": "firefox", 11:07:09 INFO - "script_repo_revision": "default", 11:07:09 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 11:07:09 INFO - "repository": "", 11:07:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 11:07:09 INFO - "buildid": "20170317090120", 11:07:09 INFO - "pgo_build": "False", 11:07:09 INFO - "basedir": "/builds/slave/test", 11:07:09 INFO - "buildnumber": 2, 11:07:09 INFO - "slavename": "t-yosemite-r7-0159", 11:07:09 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:07:09 INFO - "platform": "yosemite_r7", 11:07:09 INFO - "scheduler": "tests-pine-macosx64-talos", 11:07:09 INFO - "branch": "pine", 11:07:09 INFO - "repo_path": "projects/pine", 11:07:09 INFO - "revision": "bc5fff53646952aad7049f86e0340f919a8b5719", 11:07:09 INFO - "stage_platform": "macosx64", 11:07:09 INFO - "builduid": "d5f9bc6fb65543dda1fecf1f52e7de66", 11:07:09 INFO - "slavebuilddir": "test" 11:07:09 INFO - } 11:07:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 11:07:09 INFO - Running post-action listener: _resource_record_post_action 11:07:09 INFO - [mozharness: 2017-03-17 18:07:09.265849Z] Finished read-buildbot-config step (success) 11:07:09 INFO - [mozharness: 2017-03-17 18:07:09.265982Z] Running download-and-extract step. 11:07:09 INFO - Running pre-action listener: _resource_record_pre_action 11:07:09 INFO - Running main action method: download_and_extract 11:07:09 INFO - mkdir: /builds/slave/test/build/tests 11:07:09 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:09 INFO - trying https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 11:07:09 INFO - Downloading https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:07:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 11:07:11 INFO - Downloaded 1325 bytes. 11:07:11 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:07:11 INFO - Using the following test package requirements: 11:07:11 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:11 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 11:07:11 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:07:11 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:11 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 11:07:11 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:11 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 11:07:11 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:11 INFO - u'jsshell-mac.zip'], 11:07:11 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:11 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 11:07:11 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:07:11 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:11 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 11:07:11 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:11 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 11:07:11 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:11 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 11:07:11 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:07:11 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 11:07:11 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:07:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:07:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:07:11 INFO - Fetch https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:07:12 INFO - Expected file size: 52473014 11:07:12 INFO - Obtained file size: 52473014 11:07:29 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:07:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:07:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:07:29 INFO - Fetch https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:07:30 INFO - Expected file size: 52473014 11:07:30 INFO - Obtained file size: 52473014 11:07:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 11:07:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:07:46 INFO - Fetch https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 11:07:47 INFO - Expected file size: 14404452 11:07:47 INFO - Obtained file size: 14404452 11:07: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:47 INFO - trying https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:07:47 INFO - Downloading https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 11:07:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 11:07:49 INFO - Downloaded 75083433 bytes. 11:07:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:07:49 INFO - mkdir: /builds/slave/test/properties 11:07:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:07:49 INFO - Writing to file /builds/slave/test/properties/build_url 11:07:49 INFO - Contents: 11:07:49 INFO - build_url:https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:07:51 INFO - Running post-action listener: _resource_record_post_action 11:07:51 INFO - Running post-action listener: set_extra_try_arguments 11:07:51 INFO - [mozharness: 2017-03-17 18:07:51.027827Z] Finished download-and-extract step (success) 11:07:51 INFO - [mozharness: 2017-03-17 18:07:51.027976Z] Running populate-webroot step. 11:07:51 INFO - Running pre-action listener: _resource_record_pre_action 11:07:51 INFO - Running main action method: populate_webroot 11:07:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:07:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:07:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:07:51 INFO - '--tppagecycles', 11:07:51 INFO - '7'], 11:07:51 INFO - 'tests': ['tsvgm']}}, 11:07:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:07:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:07:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:07:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:07:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:07:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:07:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:07:51 INFO - 'talos_options': ['--disable-e10s'], 11:07:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:07:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:07:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:07:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:07:51 INFO - 'talos_options': ['--disable-e10s'], 11:07:51 INFO - 'tests': ['damp', 'tps']}, 11:07:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:07:51 INFO - 'tests': ['damp', 'tps']}, 11:07:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:07:51 INFO - 'tests': ['dromaeo_dom']}, 11:07:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:07:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:07:51 INFO - 'tests': ['basic_compositor_video']}, 11:07:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:07:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:07:51 INFO - 'tests': ['a11yr', 11:07:51 INFO - 'ts_paint', 11:07:51 INFO - 'tpaint', 11:07:51 INFO - 'sessionrestore', 11:07:51 INFO - 'sessionrestore_no_auto_restore', 11:07:51 INFO - 'tabpaint']}, 11:07:51 INFO - 'other-e10s': {'tests': ['a11yr', 11:07:51 INFO - 'ts_paint', 11:07:51 INFO - 'tpaint', 11:07:51 INFO - 'sessionrestore', 11:07:51 INFO - 'sessionrestore_no_auto_restore', 11:07:51 INFO - 'tabpaint']}, 11:07:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:07:51 INFO - 'ts_paint', 11:07:51 INFO - 'tpaint', 11:07:51 INFO - 'sessionrestore', 11:07:51 INFO - 'sessionrestore_no_auto_restore', 11:07:51 INFO - 'tabpaint']}, 11:07:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:07:51 INFO - 'ts_paint', 11:07:51 INFO - 'tpaint', 11:07:51 INFO - 'sessionrestore', 11:07:51 INFO - 'sessionrestore_no_auto_restore', 11:07:51 INFO - 'tabpaint']}, 11:07:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:07:51 INFO - 'tests': ['a11yr', 11:07:51 INFO - 'ts_paint', 11:07:51 INFO - 'tpaint', 11:07:51 INFO - 'sessionrestore', 11:07:51 INFO - 'sessionrestore_no_auto_restore', 11:07:51 INFO - 'tabpaint']}, 11:07:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:07:51 INFO - 'tests': ['a11yr', 11:07:51 INFO - 'ts_paint', 11:07:51 INFO - 'tpaint', 11:07:51 INFO - 'sessionrestore', 11:07:51 INFO - 'sessionrestore_no_auto_restore', 11:07:51 INFO - 'tabpaint']}, 11:07:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:07:51 INFO - 'tests': ['tsvgx', 11:07:51 INFO - 'tsvgr_opacity', 11:07:51 INFO - 'tart', 11:07:51 INFO - 'tscrollx', 11:07:51 INFO - 'cart', 11:07:51 INFO - 'tsvg_static']}, 11:07:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:07:51 INFO - 'tsvgr_opacity', 11:07:51 INFO - 'tart', 11:07:51 INFO - 'tscrollx', 11:07:51 INFO - 'cart', 11:07:51 INFO - 'tsvg_static']}, 11:07:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:07:51 INFO - 'talos_options': ['--disable-e10s'], 11:07:51 INFO - 'tests': ['tp5o']}, 11:07:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:07:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:07:51 INFO - 'talos_options': ['--disable-e10s', 11:07:51 INFO - '--xperf_path', 11:07:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:07:51 INFO - 'tests': ['tp5n']}, 11:07:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:07:51 INFO - 'talos_options': ['--xperf_path', 11:07:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:07:51 INFO - 'tests': ['tp5n']}}, 11:07:51 INFO - 'talos.zip': {'path': '', 11:07:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:07:51 INFO - Running post-action listener: _resource_record_post_action 11:07:51 INFO - [mozharness: 2017-03-17 18:07:51.046566Z] Finished populate-webroot step (success) 11:07:51 INFO - [mozharness: 2017-03-17 18:07:51.046652Z] Running create-virtualenv step. 11:07:51 INFO - Running pre-action listener: _resource_record_pre_action 11:07:51 INFO - Running main action method: create_virtualenv 11:07:51 INFO - Creating virtualenv /builds/slave/test/build/venv 11:07:51 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 11:07:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:07:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:07:51 INFO - Using real prefix '/tools/python27' 11:07:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:07:51 INFO - Installing distribute.............................................................................................................................................................................................done. 11:07:54 INFO - Installing pip.................done. 11:07:54 INFO - Return code: 0 11:07:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:07:55 INFO - Reading from file tmpfile_stdout 11:07:55 INFO - Output received: 11:07:55 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 11:07:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:07:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10963ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10963d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c94c40e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109418d30>, '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 0x10933fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940dbe8>, '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.NLAkXL4yuk/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.LgejRIgenJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 11:07:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:07:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:07:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLAkXL4yuk/Render', 11:07:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:07:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:07:55 INFO - 'HOME': '/Users/cltbld', 11:07:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:07:55 INFO - 'LOGNAME': 'cltbld', 11:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:55 INFO - 'MOZ_NO_REMOTE': '1', 11:07:55 INFO - 'NO_EM_RESTART': '1', 11:07:55 INFO - 'PAGER': '/bin/cat', 11:07:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:07:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:55 INFO - 'PWD': '/builds/slave/test', 11:07:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:07:55 INFO - 'SHELL': '/bin/bash', 11:07:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LgejRIgenJ/Listeners', 11:07:55 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 11:07:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:07:55 INFO - 'USER': 'cltbld', 11:07:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:07:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:55 INFO - 'XPC_FLAGS': '0x0', 11:07:55 INFO - 'XPC_SERVICE_NAME': '0', 11:07:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:55 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 11:07:55 INFO - Cleaning up... 11:07:55 INFO - Return code: 0 11:07:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:07:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10963ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10963d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c94c40e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109418d30>, '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 0x10933fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940dbe8>, '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.NLAkXL4yuk/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.LgejRIgenJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 11:07:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:07:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:07:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLAkXL4yuk/Render', 11:07:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:07:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:07:55 INFO - 'HOME': '/Users/cltbld', 11:07:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:07:55 INFO - 'LOGNAME': 'cltbld', 11:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:55 INFO - 'MOZ_NO_REMOTE': '1', 11:07:55 INFO - 'NO_EM_RESTART': '1', 11:07:55 INFO - 'PAGER': '/bin/cat', 11:07:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:07:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:55 INFO - 'PWD': '/builds/slave/test', 11:07:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:07:55 INFO - 'SHELL': '/bin/bash', 11:07:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LgejRIgenJ/Listeners', 11:07:55 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 11:07:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:07:55 INFO - 'USER': 'cltbld', 11:07:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:07:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:55 INFO - 'XPC_FLAGS': '0x0', 11:07:55 INFO - 'XPC_SERVICE_NAME': '0', 11:07:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:55 INFO - Downloading/unpacking psutil>=3.1.1 11:07: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 11:07: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 11:07: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 11:07: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 11:07: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 11:07: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 11:07:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:07:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:07:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:07:59 INFO - Installing collected packages: psutil 11:07:59 INFO - Running setup.py install for psutil 11:07:59 INFO - building 'psutil._psutil_osx' extension 11:07:59 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 11:07:59 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 11:07:59 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 11:08:00 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 11:08:00 INFO - building 'psutil._psutil_posix' extension 11:08:00 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 11:08:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:08:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:08:00 INFO - ^ 11:08:00 INFO - 1 warning generated. 11:08:00 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 11:08:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:08:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:08:00 INFO - Successfully installed psutil 11:08:00 INFO - Cleaning up... 11:08:00 INFO - Return code: 0 11:08:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:01 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10963ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10963d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c94c40e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109418d30>, '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 0x10933fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940dbe8>, '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.NLAkXL4yuk/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.LgejRIgenJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 11:08:01 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.3'] in /builds/slave/test/build 11:08:01 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.3 11:08:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLAkXL4yuk/Render', 11:08:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:01 INFO - 'HOME': '/Users/cltbld', 11:08:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:01 INFO - 'LOGNAME': 'cltbld', 11:08:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:01 INFO - 'MOZ_NO_REMOTE': '1', 11:08:01 INFO - 'NO_EM_RESTART': '1', 11:08:01 INFO - 'PAGER': '/bin/cat', 11:08:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:01 INFO - 'PWD': '/builds/slave/test', 11:08:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:01 INFO - 'SHELL': '/bin/bash', 11:08:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LgejRIgenJ/Listeners', 11:08:01 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 11:08:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:01 INFO - 'USER': 'cltbld', 11:08:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:01 INFO - 'XPC_FLAGS': '0x0', 11:08:01 INFO - 'XPC_SERVICE_NAME': '0', 11:08:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:08:01 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 11:08:01 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 11:08:01 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 11:08:01 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 11:08:01 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 11:08:01 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 11:08:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:08:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:08:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:08:04 INFO - Installing collected packages: mozsystemmonitor 11:08:04 INFO - Running setup.py install for mozsystemmonitor 11:08:04 INFO - Successfully installed mozsystemmonitor 11:08:04 INFO - Cleaning up... 11:08:04 INFO - Return code: 0 11:08:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:04 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10963ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10963d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c94c40e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109418d30>, '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 0x10933fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940dbe8>, '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.NLAkXL4yuk/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.LgejRIgenJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 11:08:04 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==2.5.1'] in /builds/slave/test/build 11:08:04 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==2.5.1 11:08:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLAkXL4yuk/Render', 11:08:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:04 INFO - 'HOME': '/Users/cltbld', 11:08:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:04 INFO - 'LOGNAME': 'cltbld', 11:08:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:04 INFO - 'MOZ_NO_REMOTE': '1', 11:08:04 INFO - 'NO_EM_RESTART': '1', 11:08:04 INFO - 'PAGER': '/bin/cat', 11:08:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:04 INFO - 'PWD': '/builds/slave/test', 11:08:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:04 INFO - 'SHELL': '/bin/bash', 11:08:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LgejRIgenJ/Listeners', 11:08:04 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 11:08:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:04 INFO - 'USER': 'cltbld', 11:08:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:04 INFO - 'XPC_FLAGS': '0x0', 11:08:04 INFO - 'XPC_SERVICE_NAME': '0', 11:08:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:05 INFO - Downloading/unpacking jsonschema==2.5.1 11:08:05 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 11:08:05 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 11:08:05 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 11:08:05 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 11:08:05 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 11:08:05 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 11:08:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:08:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:08 INFO - Downloading functools32-3.2.3-2.tar.gz 11:08:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:08:08 INFO - warning: no files found matching '*.txt' 11:08:08 INFO - no previously-included directories found matching 'build' 11:08:08 INFO - no previously-included directories found matching 'dist' 11:08:08 INFO - no previously-included directories found matching '.git*' 11:08:08 INFO - Installing collected packages: jsonschema, functools32 11:08:08 INFO - Running setup.py install for functools32 11:08:08 INFO - warning: no files found matching '*.txt' 11:08:08 INFO - no previously-included directories found matching 'build' 11:08:08 INFO - no previously-included directories found matching 'dist' 11:08:08 INFO - no previously-included directories found matching '.git*' 11:08:08 INFO - Successfully installed jsonschema functools32 11:08:08 INFO - Cleaning up... 11:08:08 INFO - Return code: 0 11:08:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10963ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10963d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c94c40e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109418d30>, '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 0x10933fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940dbe8>, '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.NLAkXL4yuk/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.LgejRIgenJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 11:08:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:08:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:08:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLAkXL4yuk/Render', 11:08:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:08 INFO - 'HOME': '/Users/cltbld', 11:08:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:08 INFO - 'LOGNAME': 'cltbld', 11:08:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:08 INFO - 'MOZ_NO_REMOTE': '1', 11:08:08 INFO - 'NO_EM_RESTART': '1', 11:08:08 INFO - 'PAGER': '/bin/cat', 11:08:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:08 INFO - 'PWD': '/builds/slave/test', 11:08:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:08 INFO - 'SHELL': '/bin/bash', 11:08:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LgejRIgenJ/Listeners', 11:08:08 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 11:08:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:08 INFO - 'USER': 'cltbld', 11:08:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:08 INFO - 'XPC_FLAGS': '0x0', 11:08:08 INFO - 'XPC_SERVICE_NAME': '0', 11:08:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:08:08 INFO - Cleaning up... 11:08:08 INFO - Return code: 0 11:08:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10963ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10963d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c94c40e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109418d30>, '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 0x10933fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940dbe8>, '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.NLAkXL4yuk/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.LgejRIgenJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 11:08:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:08:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:08:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLAkXL4yuk/Render', 11:08:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:08 INFO - 'HOME': '/Users/cltbld', 11:08:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:08 INFO - 'LOGNAME': 'cltbld', 11:08:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:08 INFO - 'MOZ_NO_REMOTE': '1', 11:08:08 INFO - 'NO_EM_RESTART': '1', 11:08:08 INFO - 'PAGER': '/bin/cat', 11:08:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:08 INFO - 'PWD': '/builds/slave/test', 11:08:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:08 INFO - 'SHELL': '/bin/bash', 11:08:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LgejRIgenJ/Listeners', 11:08:08 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 11:08:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:08 INFO - 'USER': 'cltbld', 11:08:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:08 INFO - 'XPC_FLAGS': '0x0', 11:08:08 INFO - 'XPC_SERVICE_NAME': '0', 11:08:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:09 INFO - Downloading/unpacking blobuploader==1.2.4 11:08:09 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 11:08:09 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 11:08:09 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 11:08:09 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 11:08:09 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 11:08:09 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 11:08:11 INFO - Downloading blobuploader-1.2.4.tar.gz 11:08:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:08:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:08:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:08:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:12 INFO - Downloading docopt-0.6.1.tar.gz 11:08:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:08:12 INFO - Installing collected packages: blobuploader, requests, docopt 11:08:12 INFO - Running setup.py install for blobuploader 11:08:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:08:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:08:13 INFO - Running setup.py install for requests 11:08:13 INFO - Running setup.py install for docopt 11:08:13 INFO - Successfully installed blobuploader requests docopt 11:08:13 INFO - Cleaning up... 11:08:13 INFO - Return code: 0 11:08:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:08:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:13 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 0x10963ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10963d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c94c40e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109418d30>, '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 0x10933fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940dbe8>, '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.NLAkXL4yuk/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.LgejRIgenJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 11:08:13 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 11:08:13 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 11:08:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLAkXL4yuk/Render', 11:08:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:13 INFO - 'HOME': '/Users/cltbld', 11:08:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:13 INFO - 'LOGNAME': 'cltbld', 11:08:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:13 INFO - 'MOZ_NO_REMOTE': '1', 11:08:13 INFO - 'NO_EM_RESTART': '1', 11:08:13 INFO - 'PAGER': '/bin/cat', 11:08:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:13 INFO - 'PWD': '/builds/slave/test', 11:08:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:13 INFO - 'SHELL': '/bin/bash', 11:08:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LgejRIgenJ/Listeners', 11:08:13 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 11:08:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:13 INFO - 'USER': 'cltbld', 11:08:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:13 INFO - 'XPC_FLAGS': '0x0', 11:08:13 INFO - 'XPC_SERVICE_NAME': '0', 11:08:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:08:14 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-kMRumx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:08:14 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-mFW0wH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:08:14 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-1SY5Pv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:08:14 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-IGf9Dx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:08:14 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-ktIN9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:08:14 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-kyV2Qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:08:14 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-qZwD4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:08:14 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-kI9MFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:08:14 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-x0n7aw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:08:15 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-k7J3cZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:08:15 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Lawlvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:08:15 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-APCorb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:08:15 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-l2yAw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:08:15 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-I70Hby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:08:15 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-SYkHqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:08:15 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-P6l3_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:08:15 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-pLxDx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:08:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:08:15 INFO - Running setup.py install for manifestparser 11:08:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:08:15 INFO - Running setup.py install for mozcrash 11:08:16 INFO - Running setup.py install for mozdebug 11:08:16 INFO - Running setup.py install for mozdevice 11:08:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:08:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:08:16 INFO - Running setup.py install for mozfile 11:08:16 INFO - Running setup.py install for mozhttpd 11:08:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:08:16 INFO - Running setup.py install for mozinfo 11:08:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:08:16 INFO - Running setup.py install for mozInstall 11:08:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:08:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:08:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:08:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:08:17 INFO - Running setup.py install for mozleak 11:08:17 INFO - Running setup.py install for mozlog 11:08:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:08:17 INFO - Running setup.py install for moznetwork 11:08:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:08:17 INFO - Running setup.py install for mozprocess 11:08:17 INFO - Running setup.py install for mozprofile 11:08:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:08:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:08:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:08:17 INFO - Running setup.py install for mozrunner 11:08:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:08:17 INFO - Running setup.py install for mozscreenshot 11:08:18 INFO - Running setup.py install for moztest 11:08:18 INFO - Running setup.py install for mozversion 11:08:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:08:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:08:18 INFO - Cleaning up... 11:08:18 INFO - Return code: 0 11:08:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:08:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:18 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 0x10963ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10963d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c94c40e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109418d30>, '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 0x10933fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940dbe8>, '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.NLAkXL4yuk/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.LgejRIgenJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 11:08:18 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 11:08:18 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 11:08:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLAkXL4yuk/Render', 11:08:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:18 INFO - 'HOME': '/Users/cltbld', 11:08:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:18 INFO - 'LOGNAME': 'cltbld', 11:08:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:18 INFO - 'MOZ_NO_REMOTE': '1', 11:08:18 INFO - 'NO_EM_RESTART': '1', 11:08:18 INFO - 'PAGER': '/bin/cat', 11:08:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:18 INFO - 'PWD': '/builds/slave/test', 11:08:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:18 INFO - 'SHELL': '/bin/bash', 11:08:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LgejRIgenJ/Listeners', 11:08:18 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 11:08:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:18 INFO - 'USER': 'cltbld', 11:08:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:18 INFO - 'XPC_FLAGS': '0x0', 11:08:18 INFO - 'XPC_SERVICE_NAME': '0', 11:08:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:08:18 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-XUuMub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:08:18 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)) 11:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:08:18 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-wBCAuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:08:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 11:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:08:18 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-_wi_so-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:08:19 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)) 11:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:08:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-xt2nEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:08:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 11:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:08:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-IZ_nmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:08:19 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)) 11:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:08:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-2MEpUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:08:19 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)) 11:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:08:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-eUlsOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:08:19 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)) 11:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:08:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-iUnVz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:08:19 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)) 11:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:08:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-gYRHpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:08:19 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)) 11:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:08:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-JUr8Zu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:08:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 11:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:08:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-ZVep0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:08:19 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)) 11:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:08:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-oLxIE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:08:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 11:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:08:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-RgVZqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:08:20 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)) 11:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:08:20 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-eL9D1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 11:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:08:20 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-cJ9eD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:08:20 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)) 11:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:08:20 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-jGfe0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 11:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:08:20 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-iaw3ub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:08:20 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)) 11:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:08:20 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)) 11:08:20 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:08:20 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:08:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:08:20 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 11:08:20 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 11:08:20 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 11:08:20 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 11:08:20 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 11:08:20 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 11:08:23 INFO - Downloading blessings-1.6.tar.gz 11:08:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:08:23 INFO - Installing collected packages: blessings 11:08:23 INFO - Running setup.py install for blessings 11:08:23 INFO - Successfully installed blessings 11:08:23 INFO - Cleaning up... 11:08:23 INFO - Return code: 0 11:08:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:08:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:08:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:08:23 INFO - Reading from file tmpfile_stdout 11:08:23 INFO - Current package versions: 11:08:23 INFO - blessings == 1.6 11:08:23 INFO - blobuploader == 1.2.4 11:08:23 INFO - docopt == 0.6.1 11:08:23 INFO - functools32 == 3.2.3-2 11:08:23 INFO - jsonschema == 2.5.1 11:08:23 INFO - manifestparser == 1.1 11:08:23 INFO - mozInstall == 1.12 11:08:23 INFO - mozcrash == 1.0 11:08:23 INFO - mozdebug == 0.1 11:08:23 INFO - mozdevice == 0.49 11:08:23 INFO - mozfile == 1.2 11:08:23 INFO - mozhttpd == 0.7 11:08:23 INFO - mozinfo == 0.9 11:08:23 INFO - mozleak == 0.1 11:08:23 INFO - mozlog == 3.4 11:08:23 INFO - moznetwork == 0.27 11:08:23 INFO - mozprocess == 0.25 11:08:23 INFO - mozprofile == 0.28 11:08:23 INFO - mozrunner == 6.13 11:08:23 INFO - mozscreenshot == 0.1 11:08:23 INFO - mozsystemmonitor == 0.3 11:08:23 INFO - moztest == 0.8 11:08:23 INFO - mozversion == 1.4 11:08:23 INFO - psutil == 3.1.1 11:08:23 INFO - requests == 1.2.3 11:08:23 INFO - wsgiref == 0.1.2 11:08:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:23 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 0x10963ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10963d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c94c40e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109418d30>, '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 0x10933fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940dbe8>, '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.NLAkXL4yuk/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.LgejRIgenJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 11:08:23 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 11:08:23 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 11:08:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLAkXL4yuk/Render', 11:08:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:23 INFO - 'HOME': '/Users/cltbld', 11:08:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:23 INFO - 'LOGNAME': 'cltbld', 11:08:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:23 INFO - 'MOZ_NO_REMOTE': '1', 11:08:23 INFO - 'NO_EM_RESTART': '1', 11:08:23 INFO - 'PAGER': '/bin/cat', 11:08:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:23 INFO - 'PWD': '/builds/slave/test', 11:08:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:23 INFO - 'SHELL': '/bin/bash', 11:08:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LgejRIgenJ/Listeners', 11:08:23 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 11:08:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:23 INFO - 'USER': 'cltbld', 11:08:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:23 INFO - 'XPC_FLAGS': '0x0', 11:08:23 INFO - 'XPC_SERVICE_NAME': '0', 11:08:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:24 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)) 11:08:24 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)) 11:08:24 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)) 11:08:24 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)) 11:08:24 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)) 11:08:24 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)) 11:08:24 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)) 11:08:24 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)) 11:08:24 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)) 11:08:24 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)) 11:08:24 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)) 11:08:24 INFO - Cleaning up... 11:08:24 INFO - Return code: 0 11:08:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:24 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 0x10963ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10963d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99c94c40e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109418d30>, '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 0x10933fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940dbe8>, '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.NLAkXL4yuk/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.LgejRIgenJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 11:08:24 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 11:08:24 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 11:08:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLAkXL4yuk/Render', 11:08:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:24 INFO - 'HOME': '/Users/cltbld', 11:08:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:24 INFO - 'LOGNAME': 'cltbld', 11:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:24 INFO - 'MOZ_NO_REMOTE': '1', 11:08:24 INFO - 'NO_EM_RESTART': '1', 11:08:24 INFO - 'PAGER': '/bin/cat', 11:08:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:24 INFO - 'PWD': '/builds/slave/test', 11:08:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:24 INFO - 'SHELL': '/bin/bash', 11:08:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LgejRIgenJ/Listeners', 11:08:24 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 11:08:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:24 INFO - 'USER': 'cltbld', 11:08:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:24 INFO - 'XPC_FLAGS': '0x0', 11:08:24 INFO - 'XPC_SERVICE_NAME': '0', 11:08:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:08:24 INFO - Cleaning up... 11:08:24 INFO - Return code: 0 11:08:24 INFO - Running post-action listener: _resource_record_post_action 11:08:24 INFO - Running post-action listener: _start_resource_monitoring 11:08:24 INFO - Starting resource monitoring. 11:08:24 INFO - [mozharness: 2017-03-17 18:08:24.376520Z] Finished create-virtualenv step (success) 11:08:24 INFO - [mozharness: 2017-03-17 18:08:24.377530Z] Running install step. 11:08:24 INFO - Running pre-action listener: _resource_record_pre_action 11:08:24 INFO - Running main action method: install 11:08:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:08:24 INFO - Reading from file tmpfile_stdout 11:08:24 INFO - Detecting whether we're running mozinstall >=1.0... 11:08:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:08:24 INFO - Reading from file tmpfile_stdout 11:08:24 INFO - Output received: 11:08:24 INFO - Usage: mozinstall [options] installer 11:08:24 INFO - Options: 11:08:24 INFO - -h, --help show this help message and exit 11:08:24 INFO - -d DEST, --destination=DEST 11:08:24 INFO - Directory to install application into. [default: 11:08:24 INFO - "/builds/slave/test"] 11:08:24 INFO - --app=APP Application being installed. [default: firefox] 11:08:24 INFO - mkdir: /builds/slave/test/build/application 11:08:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:08:44 INFO - Reading from file tmpfile_stdout 11:08:44 INFO - Output received: 11:08:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:08:44 INFO - Running post-action listener: _resource_record_post_action 11:08:44 INFO - [mozharness: 2017-03-17 18:08:44.887804Z] Finished install step (success) 11:08:44 INFO - [mozharness: 2017-03-17 18:08:44.887949Z] Running run-tests step. 11:08:44 INFO - Running pre-action listener: _resource_record_pre_action 11:08:44 INFO - Running main action method: run_tests 11:08:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:08:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:08:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:08:44 INFO - 2017-03-17 11:08:44.902 screenresolution[1626:7561] starting screenresolution argv=screenresolution get 11:08:44 INFO - 2017-03-17 11:08:44.905 screenresolution[1626:7561] Display 0: 1600x1200x32@60 11:08:44 INFO - 2017-03-17 11:08:44.910 screenresolution[1627:7564] starting screenresolution argv=screenresolution list 11:08:44 INFO - Available Modes on Display 0 11:08:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:08:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:08:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:08:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:08:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:08:45 INFO - Intel Iris: 11:08:45 INFO - Chipset Model: Intel Iris 11:08:45 INFO - Type: GPU 11:08:45 INFO - Bus: Built-In 11:08:45 INFO - VRAM (Dynamic, Max): 1536 MB 11:08:45 INFO - Vendor: Intel (0x8086) 11:08:45 INFO - Device ID: 0x0a2e 11:08:45 INFO - Revision ID: 0x0009 11:08:45 INFO - Displays: 11:08:45 INFO - Display: 11:08:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:08:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:08:45 INFO - Display Serial Number: 1600x1200 60 11:08:45 INFO - Main Display: Yes 11:08:45 INFO - Mirror: Off 11:08:45 INFO - Online: Yes 11:08:45 INFO - Rotation: Supported 11:08:45 INFO - Return code: 0 11:08:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:08:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:08:45 INFO - Python 2.7.3 11:08:45 INFO - Return code: 0 11:08:45 INFO - grabbing minidump binary from tooltool 11:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:45 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109418d30>, '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 0x10933fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10940dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:08:45 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 11:08:45 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 11:08:45 INFO - Calling ['/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'] with output_timeout 600 11:08:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:08:45 INFO - Return code: 0 11:08:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:08:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:08:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:08:45 INFO - ENV: RUST_BACKTRACE is now 1 11:08:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:08:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:08:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--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 11:08:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0159 --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 11:08:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLAkXL4yuk/Render', 11:08:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:45 INFO - 'HOME': '/Users/cltbld', 11:08:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:45 INFO - 'LOGNAME': 'cltbld', 11:08:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:08:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:45 INFO - 'MOZ_NO_REMOTE': '1', 11:08:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:08:45 INFO - 'NO_EM_RESTART': '1', 11:08:45 INFO - 'PAGER': '/bin/cat', 11:08:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:45 INFO - 'PWD': '/builds/slave/test', 11:08:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:08:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:45 INFO - 'RUST_BACKTRACE': '1', 11:08:45 INFO - 'SHELL': '/bin/bash', 11:08:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LgejRIgenJ/Listeners', 11:08:45 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 11:08:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:45 INFO - 'USER': 'cltbld', 11:08:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:45 INFO - 'XPC_FLAGS': '0x0', 11:08:45 INFO - 'XPC_SERVICE_NAME': '0', 11:08:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--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 11:08:45 INFO - mozversion application_buildid: 20170317090120 11:08:45 INFO - mozversion application_changeset: bc5fff53646952aad7049f86e0340f919a8b5719 11:08:45 INFO - mozversion application_display_name: Nightly 11:08:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:08:45 INFO - mozversion application_name: Firefox 11:08:45 INFO - mozversion application_remotingname: firefox 11:08:45 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 11:08:45 INFO - mozversion application_vendor: Mozilla 11:08:45 INFO - mozversion application_version: 55.0a1 11:08:45 INFO - mozversion platform_buildid: 20170317090120 11:08:45 INFO - mozversion platform_changeset: bc5fff53646952aad7049f86e0340f919a8b5719 11:08:45 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 11:08:45 INFO - mozversion platform_version: 55.0a1 11:08:45 INFO - using testdate: 1489774125 11:08:45 INFO - actual date: 1489774125 11:08:45 INFO - starting webserver on 'localhost:49250' 11:08:45 INFO - SUITE-START | Running 2 tests 11:08:45 INFO - TEST-START | dromaeo_css 11:08:45 INFO - operating with platform_type : mac_ 11:08:45 INFO - Initialising browser for dromaeo_css test... 11:08:45 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpeI5dHL/profile http://localhost:49250/getInfo.html) 11:08:47 INFO - PID 1637 | __metrics Screen width/height:1600/1200 11:08:47 INFO - PID 1637 | colorDepth:24 11:08:47 INFO - PID 1637 | Browser inner width/height: 1024/689 11:08:47 INFO - PID 1637 | __metrics 11:08:48 INFO - PID 1637 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:08:48 INFO - PID 1637 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:08:48 INFO - TEST-INFO | 1637: exit 0 11:08:48 INFO - Browser initialized. 11:08:48 INFO - Running cycle 1/1 for dromaeo_css test... 11:08:48 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpeI5dHL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:09:00 INFO - PID 1639 | RSS: Main: 234483712 11:09:00 INFO - PID 1639 | 11:12:05 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49250/tests/dromaeo/cssquery-ext.html) 11:12:07 INFO - PID 1639 | RSS: Main: 386600960 11:12:07 INFO - PID 1639 | 11:13:50 INFO - PID 1639 | ************************* 11:13:50 INFO - PID 1639 | A coding exception was thrown and uncaught in a Task. 11:13:50 INFO - PID 1639 | 11:13:50 ERROR - PID 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:13:50 INFO - PID 1639 | Full stack: 11:13:50 INFO - PID 1639 | ************************* 11:15:10 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-ext.html (next: http://localhost:49250/tests/dromaeo/cssquery-jquery.html) 11:15:10 INFO - PID 1639 | RSS: Main: 462512128 11:15:10 INFO - PID 1639 | 11:18:13 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49250/tests/dromaeo/cssquery-mootools.html) 11:18:13 INFO - PID 1639 | RSS: Main: 360325120 11:18:13 INFO - PID 1639 | 11:21:16 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49250/tests/dromaeo/cssquery-prototype.html) 11:21:16 INFO - PID 1639 | RSS: Main: 360439808 11:21:16 INFO - PID 1639 | 11:24:20 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49250/tests/dromaeo/cssquery-yui.html) 11:24:21 INFO - PID 1639 | RSS: Main: 339791872 11:24:21 INFO - PID 1639 | 11:27:24 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-yui.html (next: http://localhost:49250/tests/dromaeo/cssquery-dojo.html) 11:27:24 INFO - PID 1639 | RSS: Main: 559456256 11:27:24 INFO - PID 1639 | 11:27:24 INFO - PID 1639 | __start_tp_report 11:27:24 INFO - PID 1639 | _x_x_mozilla_page_load 11:27:24 INFO - PID 1639 | _x_x_mozilla_page_load_details 11:27:24 INFO - PID 1639 | |i|pagename|runs| 11:27:24 INFO - PID 1639 | |0;dojo.html;2664.307280107172;3372.780769249997;3405.1827561385257;3413.8293085345726;3426.1263377838645;4799.424069111711;5635.351934527531;5709.914351284734;5813.098969659699;5842.094475356316;5655.57583181261;5781.421857814227;5789.681567513796;5795.15970184323;5854.29748430189;5154.329937108189;5253.973730131344;5266.262723218752;5286.180642000496;5301.973490132544;5287.101192797234;5319.680819150844;5325.627206095575;5333.306670132897;5337.572994160477;5140.820071297505;5173.715445650488;5183.61122915779;5183.637145399824;5187.0922588546855;5128.538431541179;5172.948270517284;5175.767090480937;5180.585553155738;5186.922196167023;5077.136886729265;5135.8202462914;5139.58883289336;5143.485651434827;5154.690718556899;5632.831015069554;5640.802571909963;5659.292588426447;5674.914876276859;5738.42615738427;6283.3088360280335;6288.465480434169;6323.20959880015;6331.176946996861;6332.746690132435;4939.3084968104495;6321.4942804575985;6326;6370.872582548323;6417.96791016042;5699.74351154199;5857.824265272048;5878.706064696748;5879.147523609053;5912.733926973297;6298.905516417258;6302.243730752248;6334.746610135635;6336.20797400325;6353.618782873043;6052.878942421127;6075.453209211104;6076.5442591805795;6100.786472473442;6163;6368.522360822957;6369.71336289868;6385.840353991188;6386.042093685891;6387.201599800025;6297.748090076438;6306.684665766693;6311.873762524724;6318.557700960889;6323.177986861679;6175.536834737412;6258.249010118815;6272;6347.52393570484;6370.522210834206;4183.205191013698;4964.8758781030765;4968.552830245295;4972.2790195422185;4982.9252561211615;4392.802323895432;4430.955690443118;4445.2887537993765;4493.618042466427;4532.388127602731;5742.052561327418;5792.9710351447975;5802.6808525531505;5820.76716931318;5826.067829147316;6337.96831015842;6342.873142537123;6355.396237357444;6357.046443033582;6380.138681277968;5605.187247849039;5679.914801277984;5780.190773291659;5788.88422231553;5805.593608447368;5408.459154084639;5434.347878254555;5437.184422336602;5442.292501974797;5443.101888188484;6321.810345689637;6322.747090116344;6337.683115844189;6356.269029061532;6371.904421433775;6350.364963503613;6353.872922541431;6359.427651511386;6360.586561873463;6367.108604795425;5226.451222621722;6283.02613094971;6310.368963103653;6314.842128946813;6331.778387756406;6123.601965872204;6131.957816599936;6200.441960223601;6238.313785483502;6239;6306.306306306211;6367.90448143269;6368.968155159195;6373.171487706569;6373.362663733589;4845.5881250092725;6113.5414843886;6157.230346206724;6158.815235542941;6194.5354098443695;5974.492168165581;6031;6109.313572000287;6140.385961403824;6168.876622467616;6010.879782404415;6061.333253342217;6085.056816193497;6089.451949324582;6121.081837724377;6162.383761623802;6173.32093469727;6174.043023331391;6175.4750846176785;6182.227221597301;5784.855378615568;5882.411758824083;6037.698115094316;6054.3642917493025;6055 11:27:24 INFO - PID 1639 | |1;ext.html;657.7927952694901;727.5162017258523;789.443442373127;853.9359548033899;865.398548009134;27166.592501112475;28879.133625991202;36549.634503654954;36631.81684091576;36969.63030369699;38089.80955095221;38333;38410.42384364237;40247.59752402475;40347.19305613886;7166.498345115844;7975.840483190333;9023.142801433869;9266.073392660748;9293.814123717522;7270.890936635955;7366.74216402426;7378.557286562796;7383.926160738404;7453.031105956218;10613.946930265338;10778.029977302042;10780.676579702622;10783.46082695866;10784.622538211164;10728.73178170548;10794.946025269903;10840.728981775439;10844.078253348474;10855.565777368896;7072.92927070733;10315.329503582318;10493.370397776082;10493.790124197474;10494.160467162608;26553.203403897915;35985.46021809675;36367.454488182695;36442.45336319957;36503;3718.3307004739136;3722.1625134344827;3752.605976372496;3766.4915236443003;3866.3233934061427;26940.865295673593;26950;27035.594466083025;27068.72931270682;27146;3046.284123231039;3173.841307934594;3212.3254116635535;3250.577424934767;3269;327626.723732762;327944.3602781995;328105;328162;328239;51271.74364128155;51476;51577.742111289204;51580;51683;52870.67823304371;52871;52906;53004.08477533695;53011.73494132505;12130.75738485243;14363.856361436252;14519.564413067625;14520.346584403727;14522.491712790214;11869.821952670716;11872.703182420508;11875.278084677673;11900.107491938139;11902.285862848257;10511.579536818595;11022.118230541537;11187.776244475064;13268;13335.466581336832;4119.320312148522;4381.101874115799;4527.63778897694;4670.813167473263;4685.81256749733;885.9689910853218;900.8063266397756;995.0696099147176;1006.4263369879245;1038.9896101039044;25738.74261257401;25806.225813225632;25962.74037259641;26205.73794262071;26217.73782262191;1098.1050443888205;1110.8889111088827;1136.7101389145714;1148.8678801937883;1155.416514660108;11840.626545260224;13232.00759943008;13397.598072057854;13509.797353039712;13534.729305413837;348740;349241;349921;350421;350761;52120.739396303536;52304.21543676848;52368.47631523712;52373.47626523762;52422.47577524176;1366.091549119844;1418.6595217148017;1421.0407974617092;1421.665908511499;1436.3177490692046;24810.875945620155;24910.252692419257;25019.624705629794;25069.498610028062;25157.748422515542;42062.36906446467;47990.52009479861;48883;48890;49006.01987960292;313955;314865;315150.2727459044;315191;315640;31167.844160779052;31230.843845780626;31240.37519249557;31273;31323.21691957628;38755.8373248803;39386.606133939444;41417.58582414137;41767.791161044;41921.580784192985;27879.860600696866;28561.714382855906;33549.49675754915;34480;34946.45696059496;33424.8301309453;33499.665003349655;33974.64101436014;34393.656063440045;34540.48189277213;31567.36865262636;33721.662783372834;34100;35960.92117236487;36083.91748247557;25490.617640735032;29440.55839162457;30682.232944176576;31165.32924533415;31774.841125794224;20573.279935202198;22393.552128957657;22595.322140335815;23768.762312376653;23779.405514861573 11:27:24 INFO - PID 1639 | |2;jquery.html;374.96883570182;395.62020460358036;416.8791050595328;420.0988878854856;426.91461707658476;13097.67255818605;14949.925250373746;20908.581828363425;21075.789242107592;21120;17330.393436229733;19651.70522442161;21294.467638309052;21692.457688557755;22465.989030493627;6427.935720642804;6554.672266386698;6688.464922806188;6779.389854913057;6864.382205601495;4130.8554200602985;4361.629261512775;6645.368689974444;6779.898301525481;6783.864322713543;7394.260573942616;7741.61291935401;7760.370322232338;7776.105747838986;7787.415943804209;6337.746490140412;7447.4786764926475;7776.805579860492;7777.961110194469;7782.416318776114;7258.927410725878;7491.962540187319;7521.05986751656;7551.660175292125;7581.166071732105;27188.728112719014;27451.45097098047;27731.584026239623;27772.30569235765;27883;2828.420173864353;2884.4375346807383;2896.985515072432;2935.911922642324;2938.294809245787;15815.367385304575;15889.616557516349;16052.678946421007;16177.919110404491;16410.671786564322;1979.8020197980231;1981.5838673878502;1989.333573252962;1999.3701983875033;2017.273781438681;149113;152539;153260.2336988319;153823.23088384597;156527.520871869;17932.454654051176;20390.184392624127;21675.891620541795;21897.452563686034;22175.00212490442;35925.82037089798;36489.635103649154;36703.265934681156;36742;36788.89633311005;10255.538500767483;10321.993832990993;10527.578896844194;10541.367517948947;10716.839247411295;8391.370647201484;8399.454035487674;8406.78983025417;8605.526696031653;8613.397062205711;10528.894711052944;10619.415932123653;10624.096951759188;10772.175877563508;10815.405152716676;3525.206828463575;4071.6131967463634;4233.322668373045;4239.448871646728;4239.579740786871;869.6434461870602;876.7369789063256;884.38093334666;891.3003292415552;891.4072142025481;22418.439539011655;22821.31536053888;23145.53708925812;23182.42043948915;23185.53628927412;907.7866701325183;959.129697090676;962.5957098018849;974.7027156717128;976.5507866381402;10105.595776169019;10308.742281442874;11838.644840654793;11959.760804783855;12013.819792703116;626453;629740;630836;647471.8792181211;650894;120306;142524;142958;145512;153240.2337988303;1152.5620264299512;1280.2318608834626;1344.8655134486473;1345.165997081796;1347.5755137131773;19135.543432647635;19936.84168448817;20052;20094.698579521028;20099.196032159136;43830.561694382646;55665;56639.6016239027;58150.709246453494;59449.702751485966;82346.58826705828;82982;83331;84543.57728211566;85890;11731.41342932867;11780.58767943152;11812.76374472523;12141.817872732092;12315.07636927252;12783.744325113259;13356;13365.799513007507;13621.318934053455;13775.931120344734;9702;11495.0229230513;11513.733489316;11515.372657612825;11607.477663505331;11213.60752373663;11375.886241137814;11418.600348987746;11448.3703396314;11797.88202117968;9810.362326448758;11475.655730328104;11720.472578734149;11726.120540959633;11937.641870743892;11703.356315402734;11717.589884354007;12007.519699211934;12068;12077.335746534025;11371.48828302745;11539.307641541534;11680.182387232811;11728;11823.881761182622 11:27:24 INFO - PID 1639 | |3;mootools.html;1337.431591573581;1443.0547991065855;1535.6007438066106;1586.5954181683671;1609.9517014489566;10067.09396154346;10495.055445009957;10495.370277783339;10496.842547361786;10500.684979450609;10340.121089707383;10398.844017339747;10777;10777.407242601654;10816.242862999594;2344.0858065354482;2383.7854593086618;2384.6423036544484;2387.761223877607;2388.713354397466;2263.9434014149697;2296.230762694491;2297.2074634251157;2303.170858490942;2305.5965206088867;2324.883755812211;2334.6264597664376;2344.542814151241;2353.176388264111;2354.234873666057;2299.4021554395977;2315.097112126272;2323.314622186459;2329.1032952313312;2337.357226762637;2248.898799554024;2292.6904867842795;2301.5627030864084;2302.804261637763;2305.7924786769104;10438;10515.52680129396;10523.894761052368;10534.947325263402;10546.261761676686;5630.408807075239;5838.182654428383;5863.091220860731;5863.76544938202;5879.118132280149;3304.8512816923294;3378.1554611347165;3550.1124718820297;3599.42409214527;3603.621619729917;986.2356673578034;1090.983635245472;1195.4919159357325;1211.7818792617325;1225.9693507662291;13588.184708917497;13990.580282591436;14006.159630422208;14828.332725027292;14955.775663365059;4758.95241047592;4800;4989.625778066587;4994.9750251248515;4999.125153098194;12493.375331233583;12606.684832879251;12612.873871261354;12614.243145411305;12634;5024.949750502448;5205.505476979681;5213.374395072616;5862.267216597926;5938.317093534212;4539.387182730356;4616.815327386934;4620.306953956866;5037.4458809530925;5205.9219111713355;3389.81356025416;3973.304671682444;4013.237484877864;4033.7579745214794;4073.816678249486;1130.813415786386;1136.9875320682345;1143.4740019590915;1160.8316794064813;1167.497975870384;923.8383282925461;924.6440120553501;946.2051876423837;959.8512230604267;1005.2912696548915;6128.448439640454;6284.968575157186;6545.5090868184125;6679.966600166968;6800.761973330909;1006.1347241372414;1015.3045164062493;1017.9006672793365;1022.2895087913829;1036.1451802263164;3748.5314335708035;3752.9249415011545;4128.91742165155;4273.55127711592;4284.186004659105;12634.747305053848;13065.934670326587;13065.934670326778;13081.607551773463;13109.475620975054;12402.875971240353;12877.969762418985;13111.2133272004;13117.278549679859;13215.273159976101;9506.096920792514;9632.518374081408;9794.510274486247;9914.40513569188;9928.602855885829;5232.372115346183;6327;6617.8014659560295;6627.502937279627;6716.496262780213;11572.6528204154;11735.941320293345;11777.941110294394;11779.88220117788;11780;10038.949805250926;11674.066074713832;11699.473523691284;11964.341961192218;12162.574309899112;8583.914160858312;8611.82776344482;8680.696175634075;8764.474131552128;8784.692535761218;9624.807503850025;9645.37305075168;9720.222382209548;9737.221022318055;9741.561629726537;8667.956660216658;8730.476171429735;8732.083131271125;8757.562121894007;8868.33487488428;8369.163083691825;8504.44721093127;8534.78663033429;8556.828863422572;8570.442921210102;7508.699652013858;7665.808354791175;7667.194944530744;7677.8464430709955;7685.769426917202;5555.805546805843;5592.944070559242;5751.482366587027;5798.072308430631;5833.183354330482;5644.8306550803545;5681.602287839812;5731.426857314235;5854.209681693087;5917.112433135064 11:27:24 INFO - PID 1639 | |4;prototype.html;372.78378540446545;378.1680303332668;388.79005337117957;389.21573030343865;391.60056742123044;5376.8118115865955;5795.304563452381;5896.911546326808;5914.6155499892475;5954.910676339859;4310.288802347617;5225.111731005729;5280;5675.460831221038;5769.740361683724;4544.750038747876;4796.544328288807;4799.7360145192;4800.711957282557;4836.613070954315;4394.362817391477;4426.358178064179;4800.135975524404;4826.396700412449;4830.879228019293;4669.276262179351;4710.670253082286;4793.4487533933525;4807.807687692488;4812;4049.2711311963835;4231.984323762305;4362.476502819682;4760;4775.761211939406;4189.895252618679;4342.261815491374;4361.672874534423;4371.737695738266;4407.757573333465;5091.949080509185;5449.7275136243425;5575.804846830382;5674.404187560329;5704.828855134353;5512.7122244268585;5519.806806761784;5525.281713377275;5530.751116199741;5539.279893613804;4863.708177509361;5179.756858354006;6130.29501607312;6211.161493198406;6229.972054610984;4206.852760153411;4624.606908412756;4858.222684370519;4885.43817460993;4892.8532144035735;5093.694378337312;6206.882761102999;6368.299487056373;6375.2987171411105;6400.9039864402075;4060.4924384451942;4254.851080212177;4751.1447939370555;4894.583960363338;4933.260010998379;4899.265110233494;4972.453030166679;5079.720615366116;5084.237364395371;5110.97444512775;4735.857924262277;5072.594192464594;5410.213739923943;5471;5505.917411238835;5373.543248823816;5450.5367043801725;6126.663033533198;6157.84605384869;6167.290761562478;1555.6033211530987;1623.553522781221;1745.240820243173;1767.5492749349091;1770.4246120010791;1099.6920862158609;1128.4583399968062;1246.7568824079399;1293.6377814212035;1307.3528603341217;5752.395998420106;5769.336526299446;5774.422557744192;5777.711114444261;5780.797672081457;5698.717788497638;6278.968605156945;6388.297287298394;6417.358264173546;6441.806745797634;4988.326575912278;5371.401161221991;5607;5704.914426283609;5795.478406943311;3286.8520916558814;3312.4368857294317;3322.7674062815854;3329.467285234351;3333.6833000865363;4917.606591472673;6031.698415079229;6556.868862622721;6584.604923704593;6644.634545100066;5542.528885044736;6489.091527186198;6514.674266286666;6527.2167339919515;6593.076969224312;5974.581779275496;6329.810105696836;6561.80314590563;6573.934260657428;6578.967105164539;6340.4927605792345;6397.232332120176;6400.647964362005;6422.42198202164;6517.837054073686;5458.344998600034;5471.425500322568;5969.955257829775;6128.763001504508;6354.586951848115;4900.215965445512;5761.48146666802;6608.537402381787;6610.975298828689;6653.168353955755;4499.977500112478;4756.23900175984;4816.20532612115;5047.167217409234;5064.316317297118;5543.972280138573;6127.295361033538;6356.650384228912;6413.486921046212;6452.580582262138;5347.491988261109;5513.558915286848;5642.435756424489;5671.234383358359;6090.025595904634;5405.351357837085;5545.7781688732;6196.349383314687;6221.471174949999;6242.469390101892;5725.742341594721;5992.280926288873;6030.698465076816;6076.210092687922;6096.634201947897;5489.984352894728;5624;6173.629582225081;6194.752209911553;6212.627242365473;5145.125328694056;5599.440055994368;6044.365341639064;6081.6655083969035;6084.908726369196 11:27:24 INFO - PID 1639 | |5;yui.html;663.9302873198315;725.9927400725991;727.487121579287;782.593051613161;848.0883050720475;14930.1788401638;15122.621934451618;15680.215989200551;15721.84278157218;15950.601234969132;14246.501372451967;14305.427782888699;14341.498047568337;14343.56969290923;14360.497382591611;922.5110691333604;926.5645146781002;944.1738478831023;944.2870632672323;946.7964387656652;951.8191543606692;952.6618050592044;953.4851180362596;954.2938225712957;955.5413401567251;927.956049444375;992.161623428202;1042.681981995491;1043.8642976413055;1044.263794025214;1031.468793571315;1034.9223808214376;1040.0847254416142;1040.6409788622914;1042.9778816759617;774.4307933668749;774.7753151586033;775.0621747685308;775.7556369743569;778.0663204155037;15735;16052.197390130446;16636.33454661812;16663.416780412626;16741.162941852857;14417.207053612046;14675.926620366936;14764.409423623043;14800.851991480164;14805.333759980827;11599;11761.470733817;11904;11954.760904781855;12243.510259589606;3690.7231957603285;3733.8133093345223;3847.2690188864026;3893;3906.785126818024;109522.45238773835;109676.90323096747;109793;111527.44236278848;111606;10861.13110951122;11093.944530277297;11117.666470005914;11244.66266012021;11321.71695707614;24087.638685419734;24418;24537.877310613334;24553.87723061409;24561.877190613934;7732.265434783687;7822.726204582812;7832.138464768871;7906.130325664172;8105.432619716681;4226.1336426032585;4256.360811506118;4313.61177494027;4316.827326906951;4489.438820147481;3013.4877070898;3429.6570342965506;3521.436570148764;3528.788272703646;3601.603823579404;569.3111335284269;655.4494224851053;663.5587334422555;670.1020632352671;683.2108914204102;629.9275583307887;676.3811112831802;685.8032732881154;687.7902239816904;691.8892977123636;9561.282903782245;10009.299349045643;10138.239632027628;10148.746281343025;10164.33931794431;510.3951817096678;545.7080461952836;555.2254604826245;569.5272923473507;575.6718670357856;4694.2958556216845;4884.413870335511;5012.749362531858;5012.974935125301;5019.347484826949;90678.09321906828;90937.54531227301;92278.15443691089;93181;94146;83183;84405.57797211097;84622.57688711517;84834;85832.57083714541;53771;71964.64017679878;76846.6157669208;77502;78505;6843.794686159423;9558.95220523893;9599.376040557341;9606.759831004001;9649.903500965182;48445.54663360105;50522;50734;51587.48412515977;52003;63383;64790.67604661947;65222;65469.672651636436;65749;11499;11576.594819181288;11584.710382240512;11636.94181529087;12538.373081345713;12685.873141268468;12776.787482669615;12788.360581970679;12891.613251602466;12982;11755.471003804678;11807.003021810988;11824.822627660764;11840.348780817138;11991.796236796634;11645.301281923112;11815.519038872133;11819.645410637695;11820.763584728085;12317.07621928341;11680;11698.94150529276;11714.531418743154;11810.17328787011;11829.526818927146;10032.64885728996;10200.489975501047;10201.897981020094;10372.325798823053;10799.244052916221;6732.899006515004;9172.83537179672;9765.535169724599;10204.693859184237;10228.335158214692 11:27:24 INFO - PID 1639 | __end_tp_report 11:27:24 INFO - PID 1639 | __start_cc_report 11:27:24 INFO - PID 1639 | _x_x_mozilla_cycle_collect,1477 11:27:24 INFO - PID 1639 | __end_cc_report 11:27:24 INFO - PID 1639 | __startTimestamp1489775244872__endTimestamp 11:27:24 INFO - PID 1639 | 11:27:24 INFO - PID 1639 | ------- Summary: start ------- 11:27:24 INFO - PID 1639 | Number of tests: 6 11:27:24 INFO - PID 1639 | 11:27:24 INFO - PID 1639 | [#0] dojo.html Cycles:165 Average:5759.13 Median:6031.00 stddev:691.24 (11.5%) stddev-sans-first:649.33 11:27:24 INFO - PID 1639 | Values: 2664.3 3372.8 3405.2 3413.8 3426.1 4799.4 5635.4 5709.9 5813.1 5842.1 5655.6 5781.4 5789.7 5795.2 5854.3 5154.3 5254.0 5266.3 5286.2 5302.0 5287.1 5319.7 5325.6 5333.3 5337.6 5140.8 5173.7 5183.6 5183.6 5187.1 5128.5 5172.9 5175.8 5180.6 5186.9 5077.1 5135.8 5139.6 5143.5 5154.7 5632.8 5640.8 5659.3 5674.9 5738.4 6283.3 6288.5 6323.2 6331.2 6332.7 4939.3 6321.5 6326.0 6370.9 6418.0 5699.7 5857.8 5878.7 5879.1 5912.7 6298.9 6302.2 6334.7 6336.2 6353.6 6052.9 6075.5 6076.5 6100.8 6163.0 6368.5 6369.7 6385.8 6386.0 6387.2 6297.7 6306.7 6311.9 6318.6 6323.2 6175.5 6258.2 6272.0 6347.5 6370.5 4183.2 4964.9 4968.6 4972.3 4982.9 4392.8 4431.0 4445.3 4493.6 4532.4 5742.1 5793.0 5802.7 5820.8 5826.1 6338.0 6342.9 6355.4 6357.0 6380.1 5605.2 5679.9 5780.2 5788.9 5805.6 5408.5 5434.3 5437.2 5442.3 5443.1 6321.8 6322.7 6337.7 6356.3 6371.9 6350.4 6353.9 6359.4 6360.6 6367.1 5226.5 6283.0 6310.4 6314.8 6331.8 6123.6 6132.0 6200.4 6238.3 6239.0 6306.3 6367.9 6369.0 6373.2 6373.4 4845.6 6113.5 6157.2 6158.8 6194.5 5974.5 6031.0 6109.3 6140.4 6168.9 6010.9 6061.3 6085.1 6089.5 6121.1 6162.4 6173.3 6174.0 6175.5 6182.2 5784.9 5882.4 6037.7 6054.4 6055.0 11:27:24 INFO - PID 1639 | 11:27:24 INFO - PID 1639 | [#1] ext.html Cycles:180 Average:47773.07 Median:3259.79 stddev:87145.54 (2673.3%) stddev-sans-first:87318.21 11:27:24 INFO - PID 1639 | Values: 657.8 727.5 789.4 853.9 865.4 27166.6 28879.1 36549.6 36631.8 36969.6 38089.8 38333.0 38410.4 40247.6 40347.2 7166.5 7975.8 9023.1 9266.1 9293.8 7270.9 7366.7 7378.6 7383.9 7453.0 10613.9 10778.0 10780.7 10783.5 10784.6 10728.7 10794.9 10840.7 10844.1 10855.6 7072.9 10315.3 10493.4 10493.8 10494.2 26553.2 35985.5 36367.5 36442.5 36503.0 3718.3 3722.2 3752.6 3766.5 3866.3 26940.9 26950.0 27035.6 27068.7 27146.0 3046.3 3173.8 3212.3 3250.6 3269.0 327626.7 327944.4 328105.0 328162.0 328239.0 51271.7 51476.0 51577.7 51580.0 51683.0 52870.7 52871.0 52906.0 53004.1 53011.7 12130.8 14363.9 14519.6 14520.3 14522.5 11869.8 11872.7 11875.3 11900.1 11902.3 10511.6 11022.1 11187.8 13268.0 13335.5 4119.3 4381.1 4527.6 4670.8 4685.8 886.0 900.8 995.1 1006.4 1039.0 25738.7 25806.2 25962.7 26205.7 26217.7 1098.1 1110.9 1136.7 1148.9 1155.4 11840.6 13232.0 13397.6 13509.8 13534.7 348740.0 349241.0 349921.0 350421.0 350761.0 52120.7 52304.2 52368.5 52373.5 52422.5 1366.1 1418.7 1421.0 1421.7 1436.3 24810.9 24910.3 25019.6 25069.5 25157.7 42062.4 47990.5 48883.0 48890.0 49006.0 313955.0 314865.0 315150.3 315191.0 315640.0 31167.8 31230.8 31240.4 31273.0 31323.2 38755.8 39386.6 41417.6 41767.8 41921.6 27879.9 28561.7 33549.5 34480.0 34946.5 33424.8 33499.7 33974.6 34393.7 34540.5 31567.4 33721.7 34100.0 35960.9 36083.9 25490.6 29440.6 30682.2 31165.3 31774.8 20573.3 22393.6 22595.3 23768.8 23779.4 11:27:24 INFO - PID 1639 | 11:27:24 INFO - PID 1639 | [#2] jquery.html Cycles:180 Average:39537.60 Median:22036.23 stddev:106928.48 (485.2%) stddev-sans-first:107188.01 11:27:24 INFO - PID 1639 | Values: 375.0 395.6 416.9 420.1 426.9 13097.7 14949.9 20908.6 21075.8 21120.0 17330.4 19651.7 21294.5 21692.5 22466.0 6427.9 6554.7 6688.5 6779.4 6864.4 4130.9 4361.6 6645.4 6779.9 6783.9 7394.3 7741.6 7760.4 7776.1 7787.4 6337.7 7447.5 7776.8 7778.0 7782.4 7258.9 7492.0 7521.1 7551.7 7581.2 27188.7 27451.5 27731.6 27772.3 27883.0 2828.4 2884.4 2897.0 2935.9 2938.3 15815.4 15889.6 16052.7 16177.9 16410.7 1979.8 1981.6 1989.3 1999.4 2017.3 149113.0 152539.0 153260.2 153823.2 156527.5 17932.5 20390.2 21675.9 21897.5 22175.0 35925.8 36489.6 36703.3 36742.0 36788.9 10255.5 10322.0 10527.6 10541.4 10716.8 8391.4 8399.5 8406.8 8605.5 8613.4 10528.9 10619.4 10624.1 10772.2 10815.4 3525.2 4071.6 4233.3 4239.4 4239.6 869.6 876.7 884.4 891.3 891.4 22418.4 22821.3 23145.5 23182.4 23185.5 907.8 959.1 962.6 974.7 976.6 10105.6 10308.7 11838.6 11959.8 12013.8 626453.0 629740.0 630836.0 647471.9 650894.0 120306.0 142524.0 142958.0 145512.0 153240.2 1152.6 1280.2 1344.9 1345.2 1347.6 19135.5 19936.8 20052.0 20094.7 20099.2 43830.6 55665.0 56639.6 58150.7 59449.7 82346.6 82982.0 83331.0 84543.6 85890.0 11731.4 11780.6 11812.8 12141.8 12315.1 12783.7 13356.0 13365.8 13621.3 13775.9 9702.0 11495.0 11513.7 11515.4 11607.5 11213.6 11375.9 11418.6 11448.4 11797.9 9810.4 11475.7 11720.5 11726.1 11937.6 11703.4 11717.6 12007.5 12068.0 12077.3 11371.5 11539.3 11680.2 11728.0 11823.9 11:27:24 INFO - PID 1639 | 11:27:24 INFO - PID 1639 | [#3] mootools.html Cycles:180 Average:6518.58 Median:3341.50 stddev:4026.04 (120.5%) stddev-sans-first:4018.51 11:27:24 INFO - PID 1639 | Values: 1337.4 1443.1 1535.6 1586.6 1610.0 10067.1 10495.1 10495.4 10496.8 10500.7 10340.1 10398.8 10777.0 10777.4 10816.2 2344.1 2383.8 2384.6 2387.8 2388.7 2263.9 2296.2 2297.2 2303.2 2305.6 2324.9 2334.6 2344.5 2353.2 2354.2 2299.4 2315.1 2323.3 2329.1 2337.4 2248.9 2292.7 2301.6 2302.8 2305.8 10438.0 10515.5 10523.9 10534.9 10546.3 5630.4 5838.2 5863.1 5863.8 5879.1 3304.9 3378.2 3550.1 3599.4 3603.6 986.2 1091.0 1195.5 1211.8 1226.0 13588.2 13990.6 14006.2 14828.3 14955.8 4759.0 4800.0 4989.6 4995.0 4999.1 12493.4 12606.7 12612.9 12614.2 12634.0 5024.9 5205.5 5213.4 5862.3 5938.3 4539.4 4616.8 4620.3 5037.4 5205.9 3389.8 3973.3 4013.2 4033.8 4073.8 1130.8 1137.0 1143.5 1160.8 1167.5 923.8 924.6 946.2 959.9 1005.3 6128.4 6285.0 6545.5 6680.0 6800.8 1006.1 1015.3 1017.9 1022.3 1036.1 3748.5 3752.9 4128.9 4273.6 4284.2 12634.7 13065.9 13065.9 13081.6 13109.5 12402.9 12878.0 13111.2 13117.3 13215.3 9506.1 9632.5 9794.5 9914.4 9928.6 5232.4 6327.0 6617.8 6627.5 6716.5 11572.7 11735.9 11777.9 11779.9 11780.0 10038.9 11674.1 11699.5 11964.3 12162.6 8583.9 8611.8 8680.7 8764.5 8784.7 9624.8 9645.4 9720.2 9737.2 9741.6 8668.0 8730.5 8732.1 8757.6 8868.3 8369.2 8504.4 8534.8 8556.8 8570.4 7508.7 7665.8 7667.2 7677.8 7685.8 5555.8 5592.9 5751.5 5798.1 5833.2 5644.8 5681.6 5731.4 5854.2 5917.1 11:27:24 INFO - PID 1639 | 11:27:24 INFO - PID 1639 | [#4] prototype.html Cycles:180 Average:5108.89 Median:5509.31 stddev:1438.89 (26.1%) stddev-sans-first:1398.32 11:27:24 INFO - PID 1639 | Values: 372.8 378.2 388.8 389.2 391.6 5376.8 5795.3 5896.9 5914.6 5954.9 4310.3 5225.1 5280.0 5675.5 5769.7 4544.8 4796.5 4799.7 4800.7 4836.6 4394.4 4426.4 4800.1 4826.4 4830.9 4669.3 4710.7 4793.4 4807.8 4812.0 4049.3 4232.0 4362.5 4760.0 4775.8 4189.9 4342.3 4361.7 4371.7 4407.8 5091.9 5449.7 5575.8 5674.4 5704.8 5512.7 5519.8 5525.3 5530.8 5539.3 4863.7 5179.8 6130.3 6211.2 6230.0 4206.9 4624.6 4858.2 4885.4 4892.9 5093.7 6206.9 6368.3 6375.3 6400.9 4060.5 4254.9 4751.1 4894.6 4933.3 4899.3 4972.5 5079.7 5084.2 5111.0 4735.9 5072.6 5410.2 5471.0 5505.9 5373.5 5450.5 6126.7 6157.8 6167.3 1555.6 1623.6 1745.2 1767.5 1770.4 1099.7 1128.5 1246.8 1293.6 1307.4 5752.4 5769.3 5774.4 5777.7 5780.8 5698.7 6279.0 6388.3 6417.4 6441.8 4988.3 5371.4 5607.0 5704.9 5795.5 3286.9 3312.4 3322.8 3329.5 3333.7 4917.6 6031.7 6556.9 6584.6 6644.6 5542.5 6489.1 6514.7 6527.2 6593.1 5974.6 6329.8 6561.8 6573.9 6579.0 6340.5 6397.2 6400.6 6422.4 6517.8 5458.3 5471.4 5970.0 6128.8 6354.6 4900.2 5761.5 6608.5 6611.0 6653.2 4500.0 4756.2 4816.2 5047.2 5064.3 5544.0 6127.3 6356.7 6413.5 6452.6 5347.5 5513.6 5642.4 5671.2 6090.0 5405.4 5545.8 6196.3 6221.5 6242.5 5725.7 5992.3 6030.7 6076.2 6096.6 5490.0 5624.0 6173.6 6194.8 6212.6 5145.1 5599.4 6044.4 6081.7 6084.9 11:27:24 INFO - PID 1639 | 11:27:24 INFO - PID 1639 | [#5] yui.html Cycles:180 Average:19811.55 Median:3790.54 stddev:28348.16 (747.9%) stddev-sans-first:28391.22 11:27:24 INFO - PID 1639 | Values: 663.9 726.0 727.5 782.6 848.1 14930.2 15122.6 15680.2 15721.8 15950.6 14246.5 14305.4 14341.5 14343.6 14360.5 922.5 926.6 944.2 944.3 946.8 951.8 952.7 953.5 954.3 955.5 928.0 992.2 1042.7 1043.9 1044.3 1031.5 1034.9 1040.1 1040.6 1043.0 774.4 774.8 775.1 775.8 778.1 15735.0 16052.2 16636.3 16663.4 16741.2 14417.2 14675.9 14764.4 14800.9 14805.3 11599.0 11761.5 11904.0 11954.8 12243.5 3690.7 3733.8 3847.3 3893.0 3906.8 109522.5 109676.9 109793.0 111527.4 111606.0 10861.1 11093.9 11117.7 11244.7 11321.7 24087.6 24418.0 24537.9 24553.9 24561.9 7732.3 7822.7 7832.1 7906.1 8105.4 4226.1 4256.4 4313.6 4316.8 4489.4 3013.5 3429.7 3521.4 3528.8 3601.6 569.3 655.4 663.6 670.1 683.2 629.9 676.4 685.8 687.8 691.9 9561.3 10009.3 10138.2 10148.7 10164.3 510.4 545.7 555.2 569.5 575.7 4694.3 4884.4 5012.7 5013.0 5019.3 90678.1 90937.5 92278.2 93181.0 94146.0 83183.0 84405.6 84622.6 84834.0 85832.6 53771.0 71964.6 76846.6 77502.0 78505.0 6843.8 9559.0 9599.4 9606.8 9649.9 48445.5 50522.0 50734.0 51587.5 52003.0 63383.0 64790.7 65222.0 65469.7 65749.0 11499.0 11576.6 11584.7 11636.9 12538.4 12685.9 12776.8 12788.4 12891.6 12982.0 11755.5 11807.0 11824.8 11840.3 11991.8 11645.3 11815.5 11819.6 11820.8 12317.1 11680.0 11698.9 11714.5 11810.2 11829.5 10032.6 10200.5 10201.9 10372.3 10799.2 6732.9 9172.8 9765.5 10204.7 10228.3 11:27:24 INFO - PID 1639 | -------- Summary: end -------- 11:27:24 INFO - PID 1639 | 11:27:25 INFO - TEST-INFO | 1639: exit 0 11:27:25 INFO - TEST-OK | dromaeo_css | took 1120073ms 11:27:25 INFO - TEST-START | kraken 11:27:25 INFO - operating with platform_type : mac_ 11:27:25 INFO - Initialising browser for kraken test... 11:27:25 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpN0Q43Q/profile http://localhost:49250/getInfo.html) 11:27:28 INFO - PID 1645 | __metrics Screen width/height:1600/1200 11:27:28 INFO - PID 1645 | colorDepth:24 11:27:28 INFO - PID 1645 | Browser inner width/height: 1024/689 11:27:28 INFO - PID 1645 | __metrics 11:27:28 INFO - PID 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:27:28 INFO - PID 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:27:28 INFO - TEST-INFO | 1645: exit 0 11:27:28 INFO - Browser initialized. 11:27:28 INFO - Running cycle 1/1 for kraken test... 11:27:28 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpN0Q43Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:27:41 INFO - PID 1646 | RSS: Main: 234700800 11:27:41 INFO - PID 1646 | 11:27:44 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection) 11:27:45 INFO - PID 1646 | RSS: Main: 284893184 11:27:45 INFO - PID 1646 | 11:27:47 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-dft) 11:27:48 INFO - PID 1646 | RSS: Main: 287223808 11:27:48 INFO - PID 1646 | 11:27:51 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-fft) 11:27:52 INFO - PID 1646 | RSS: Main: 379891712 11:27:52 INFO - PID 1646 | 11:27:54 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator) 11:27:55 INFO - PID 1646 | RSS: Main: 279891968 11:27:55 INFO - PID 1646 | 11:27:57 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:27:57 INFO - PID 1646 | RSS: Main: 283594752 11:27:57 INFO - PID 1646 | 11:28:00 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom) 11:28:01 INFO - PID 1646 | RSS: Main: 325828608 11:28:01 INFO - PID 1646 | 11:28:04 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate) 11:28:05 INFO - PID 1646 | RSS: Main: 333619200 11:28:05 INFO - PID 1646 | 11:28:08 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial) 11:28:08 INFO - PID 1646 | RSS: Main: 338456576 11:28:08 INFO - PID 1646 | 11:28:10 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:28:10 INFO - PID 1646 | RSS: Main: 304250880 11:28:10 INFO - PID 1646 | 11:28:12 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:28:13 INFO - PID 1646 | RSS: Main: 306487296 11:28:13 INFO - PID 1646 | 11:28:15 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:28:16 INFO - PID 1646 | RSS: Main: 307793920 11:28:16 INFO - PID 1646 | 11:28:19 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:28:19 INFO - PID 1646 | RSS: Main: 326086656 11:28:19 INFO - PID 1646 | 11:28:22 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:28:23 INFO - PID 1646 | RSS: Main: 320229376 11:28:23 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49250/tests/kraken/driver.html?testName=ai-astar) 11:28:25 INFO - PID 1646 | RSS: Main: 382173184 11:28:25 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | __start_tp_report 11:28:25 INFO - PID 1646 | _x_x_mozilla_page_load 11:28:25 INFO - PID 1646 | _x_x_mozilla_page_load_details 11:28:25 INFO - PID 1646 | |i|pagename|runs| 11:28:25 INFO - PID 1646 | |0;ai-astar;115;116;116;115;114;115;116;115;116;115 11:28:25 INFO - PID 1646 | |1;audio-beat-detection;142;112;109;147;95;148;138;143;134;96 11:28:25 INFO - PID 1646 | |2;audio-dft;186;185;183;185;182;185;189;172;249;263 11:28:25 INFO - PID 1646 | |3;audio-fft;79;59;59;89;63;62;60;57;77;59 11:28:25 INFO - PID 1646 | |4;audio-oscillator;86;71;87;70;69;71;85;69;69;83 11:28:25 INFO - PID 1646 | |5;imaging-gaussian-blur;88;88;88;88;88;103;134;135;88;88 11:28:25 INFO - PID 1646 | |6;imaging-darkroom;94;101;147;95;95;94;94;94;94;94 11:28:25 INFO - PID 1646 | |7;imaging-desaturate;87;86;83;82;83;86;85;84;84;96 11:28:25 INFO - PID 1646 | |8;json-parse-financial;63;61;60;62;64;62;62;62;62;63 11:28:25 INFO - PID 1646 | |9;json-stringify-tinderbox;54;61;70;56;65;61;58;59;60;59 11:28:25 INFO - PID 1646 | |10;stanford-crypto-aes;81;84;86;83;81;82;80;82;83;81 11:28:25 INFO - PID 1646 | |11;stanford-crypto-ccm;137;142;145;136;136;142;136;138;176;158 11:28:25 INFO - PID 1646 | |12;stanford-crypto-pbkdf2;177;186;181;185;182;186;180;184;209;229 11:28:25 INFO - PID 1646 | |13;stanford-crypto-sha256-iterative;67;66;68;66;71;80;67;69;69;80 11:28:25 INFO - PID 1646 | __end_tp_report 11:28:25 INFO - PID 1646 | __start_cc_report 11:28:25 INFO - PID 1646 | _x_x_mozilla_cycle_collect,2193 11:28:25 INFO - PID 1646 | __end_cc_report 11:28:25 INFO - PID 1646 | __startTimestamp1489775305618__endTimestamp 11:28:25 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | ------- Summary: start ------- 11:28:25 INFO - PID 1646 | Number of tests: 14 11:28:25 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | [#0] ai-astar Cycles:10 Average:115.30 Median:115.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 11:28:25 INFO - PID 1646 | Values: 115.0 116.0 116.0 115.0 114.0 115.0 116.0 115.0 116.0 115.0 11:28:25 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | [#1] audio-beat-detection Cycles:10 Average:126.40 Median:145.00 stddev:21.14 (14.6%) stddev-sans-first:21.66 11:28:25 INFO - PID 1646 | Values: 142.0 112.0 109.0 147.0 95.0 148.0 138.0 143.0 134.0 96.0 11:28:25 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | [#2] audio-dft Cycles:10 Average:197.90 Median:185.50 stddev:31.12 (16.8%) stddev-sans-first:32.71 11:28:25 INFO - PID 1646 | Values: 186.0 185.0 183.0 185.0 182.0 185.0 189.0 172.0 249.0 263.0 11:28:25 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | [#3] audio-fft Cycles:10 Average:66.40 Median:62.50 stddev:11.09 (17.7%) stddev-sans-first:10.78 11:28:25 INFO - PID 1646 | Values: 79.0 59.0 59.0 89.0 63.0 62.0 60.0 57.0 77.0 59.0 11:28:25 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:77.00 stddev:8.06 (10.5%) stddev-sans-first:7.69 11:28:25 INFO - PID 1646 | Values: 86.0 71.0 87.0 70.0 69.0 71.0 85.0 69.0 69.0 83.0 11:28:25 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | [#5] imaging-gaussian-blur Cycles:10 Average:98.80 Median:88.00 stddev:19.39 (22.0%) stddev-sans-first:20.17 11:28:25 INFO - PID 1646 | Values: 88.0 88.0 88.0 88.0 88.0 103.0 134.0 135.0 88.0 88.0 11:28:25 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | [#6] imaging-darkroom Cycles:10 Average:100.20 Median:94.00 stddev:16.59 (17.6%) stddev-sans-first:17.44 11:28:25 INFO - PID 1646 | Values: 94.0 101.0 147.0 95.0 95.0 94.0 94.0 94.0 94.0 94.0 11:28:25 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:85.50 stddev:3.98 (4.7%) stddev-sans-first:4.19 11:28:25 INFO - PID 1646 | Values: 87.0 86.0 83.0 82.0 83.0 86.0 85.0 84.0 84.0 96.0 11:28:25 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:62.00 stddev:1.10 (1.8%) stddev-sans-first:1.12 11:28:25 INFO - PID 1646 | Values: 63.0 61.0 60.0 62.0 64.0 62.0 62.0 62.0 62.0 63.0 11:28:25 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.50 stddev:4.52 (7.5%) stddev-sans-first:4.18 11:28:25 INFO - PID 1646 | Values: 54.0 61.0 70.0 56.0 65.0 61.0 58.0 59.0 60.0 59.0 11:28:25 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:82.50 stddev:1.77 (2.1%) stddev-sans-first:1.81 11:28:25 INFO - PID 1646 | Values: 81.0 84.0 86.0 83.0 81.0 82.0 80.0 82.0 83.0 81.0 11:28:25 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | [#11] stanford-crypto-ccm Cycles:10 Average:144.60 Median:142.00 stddev:12.92 (9.1%) stddev-sans-first:13.41 11:28:25 INFO - PID 1646 | Values: 137.0 142.0 145.0 136.0 136.0 142.0 136.0 138.0 176.0 158.0 11:28:25 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.90 Median:185.50 stddev:16.29 (8.8%) stddev-sans-first:16.60 11:28:25 INFO - PID 1646 | Values: 177.0 186.0 181.0 185.0 182.0 186.0 180.0 184.0 209.0 229.0 11:28:25 INFO - PID 1646 | 11:28:25 INFO - PID 1646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:69.00 stddev:5.33 (7.7%) stddev-sans-first:5.52 11:28:25 INFO - PID 1646 | Values: 67.0 66.0 68.0 66.0 71.0 80.0 67.0 69.0 69.0 80.0 11:28:25 INFO - PID 1646 | -------- Summary: end -------- 11:28:25 INFO - PID 1646 | 11:28:26 INFO - TEST-INFO | 1646: exit 0 11:28:26 INFO - TEST-OK | kraken | took 61068ms 11:28:26 INFO - SUITE-END | took 1181s 11:28:26 INFO - Completed test suite (00:19:41) 11:28:26 INFO - javascript benchmark 11:28:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7668.933658723973, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2664.307280107172, 3372.780769249997, 3405.1827561385257, 3413.8293085345726, 3426.1263377838645, 4799.424069111711, 5635.351934527531, 5709.914351284734, 5813.098969659699, 5842.094475356316, 5655.57583181261, 5781.421857814227, 5789.681567513796, 5795.15970184323, 5854.29748430189, 5154.329937108189, 5253.973730131344, 5266.262723218752, 5286.180642000496, 5301.973490132544, 5287.101192797234, 5319.680819150844, 5325.627206095575, 5333.306670132897, 5337.572994160477, 5140.820071297505, 5173.715445650488, 5183.61122915779, 5183.637145399824, 5187.0922588546855, 5128.538431541179, 5172.948270517284, 5175.767090480937, 5180.585553155738, 5186.922196167023, 5077.136886729265, 5135.8202462914, 5139.58883289336, 5143.485651434827, 5154.690718556899, 5632.831015069554, 5640.802571909963, 5659.292588426447, 5674.914876276859, 5738.42615738427, 6283.3088360280335, 6288.465480434169, 6323.20959880015, 6331.176946996861, 6332.746690132435, 4939.3084968104495, 6321.4942804575985, 6326.0, 6370.872582548323, 6417.96791016042, 5699.74351154199, 5857.824265272048, 5878.706064696748, 5879.147523609053, 5912.733926973297, 6298.905516417258, 6302.243730752248, 6334.746610135635, 6336.20797400325, 6353.618782873043, 6052.878942421127, 6075.453209211104, 6076.5442591805795, 6100.786472473442, 6163.0, 6368.522360822957, 6369.71336289868, 6385.840353991188, 6386.042093685891, 6387.201599800025, 6297.748090076438, 6306.684665766693, 6311.873762524724, 6318.557700960889, 6323.177986861679, 6175.536834737412, 6258.249010118815, 6272.0, 6347.52393570484, 6370.522210834206, 4183.205191013698, 4964.8758781030765, 4968.552830245295, 4972.2790195422185, 4982.9252561211615, 4392.802323895432, 4430.955690443118, 4445.2887537993765, 4493.618042466427, 4532.388127602731, 5742.052561327418, 5792.9710351447975, 5802.6808525531505, 5820.76716931318, 5826.067829147316, 6337.96831015842, 6342.873142537123, 6355.396237357444, 6357.046443033582, 6380.138681277968, 5605.187247849039, 5679.914801277984, 5780.190773291659, 5788.88422231553, 5805.593608447368, 5408.459154084639, 5434.347878254555, 5437.184422336602, 5442.292501974797, 5443.101888188484, 6321.810345689637, 6322.747090116344, 6337.683115844189, 6356.269029061532, 6371.904421433775, 6350.364963503613, 6353.872922541431, 6359.427651511386, 6360.586561873463, 6367.108604795425, 5226.451222621722, 6283.02613094971, 6310.368963103653, 6314.842128946813, 6331.778387756406, 6123.601965872204, 6131.957816599936, 6200.441960223601, 6238.313785483502, 6239.0, 6306.306306306211, 6367.90448143269, 6368.968155159195, 6373.171487706569, 6373.362663733589, 4845.5881250092725, 6113.5414843886, 6157.230346206724, 6158.815235542941, 6194.5354098443695, 5974.492168165581, 6031.0, 6109.313572000287, 6140.385961403824, 6168.876622467616, 6010.879782404415, 6061.333253342217, 6085.056816193497, 6089.451949324582, 6121.081837724377, 6162.383761623802, 6173.32093469727, 6174.043023331391, 6175.4750846176785, 6182.227221597301, 5784.855378615568, 5882.411758824083, 6037.698115094316, 6054.3642917493025, 6055.0], "value": 5713.19667449647, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.7927952694901, 727.5162017258523, 789.443442373127, 853.9359548033899, 865.398548009134, 27166.592501112475, 28879.133625991202, 36549.634503654954, 36631.81684091576, 36969.63030369699, 38089.80955095221, 38333.0, 38410.42384364237, 40247.59752402475, 40347.19305613886, 7166.498345115844, 7975.840483190333, 9023.142801433869, 9266.073392660748, 9293.814123717522, 7270.890936635955, 7366.74216402426, 7378.557286562796, 7383.926160738404, 7453.031105956218, 10613.946930265338, 10778.029977302042, 10780.676579702622, 10783.46082695866, 10784.622538211164, 10728.73178170548, 10794.946025269903, 10840.728981775439, 10844.078253348474, 10855.565777368896, 7072.92927070733, 10315.329503582318, 10493.370397776082, 10493.790124197474, 10494.160467162608, 26553.203403897915, 35985.46021809675, 36367.454488182695, 36442.45336319957, 36503.0, 3718.3307004739136, 3722.1625134344827, 3752.605976372496, 3766.4915236443003, 3866.3233934061427, 26940.865295673593, 26950.0, 27035.594466083025, 27068.72931270682, 27146.0, 3046.284123231039, 3173.841307934594, 3212.3254116635535, 3250.577424934767, 3269.0, 327626.723732762, 327944.3602781995, 328105.0, 328162.0, 328239.0, 51271.74364128155, 51476.0, 51577.742111289204, 51580.0, 51683.0, 52870.67823304371, 52871.0, 52906.0, 53004.08477533695, 53011.73494132505, 12130.75738485243, 14363.856361436252, 14519.564413067625, 14520.346584403727, 14522.491712790214, 11869.821952670716, 11872.703182420508, 11875.278084677673, 11900.107491938139, 11902.285862848257, 10511.579536818595, 11022.118230541537, 11187.776244475064, 13268.0, 13335.466581336832, 4119.320312148522, 4381.101874115799, 4527.63778897694, 4670.813167473263, 4685.81256749733, 885.9689910853218, 900.8063266397756, 995.0696099147176, 1006.4263369879245, 1038.9896101039044, 25738.74261257401, 25806.225813225632, 25962.74037259641, 26205.73794262071, 26217.73782262191, 1098.1050443888205, 1110.8889111088827, 1136.7101389145714, 1148.8678801937883, 1155.416514660108, 11840.626545260224, 13232.00759943008, 13397.598072057854, 13509.797353039712, 13534.729305413837, 348740.0, 349241.0, 349921.0, 350421.0, 350761.0, 52120.739396303536, 52304.21543676848, 52368.47631523712, 52373.47626523762, 52422.47577524176, 1366.091549119844, 1418.6595217148017, 1421.0407974617092, 1421.665908511499, 1436.3177490692046, 24810.875945620155, 24910.252692419257, 25019.624705629794, 25069.498610028062, 25157.748422515542, 42062.36906446467, 47990.52009479861, 48883.0, 48890.0, 49006.01987960292, 313955.0, 314865.0, 315150.2727459044, 315191.0, 315640.0, 31167.844160779052, 31230.843845780626, 31240.37519249557, 31273.0, 31323.21691957628, 38755.8373248803, 39386.606133939444, 41417.58582414137, 41767.791161044, 41921.580784192985, 27879.860600696866, 28561.714382855906, 33549.49675754915, 34480.0, 34946.45696059496, 33424.8301309453, 33499.665003349655, 33974.64101436014, 34393.656063440045, 34540.48189277213, 31567.36865262636, 33721.662783372834, 34100.0, 35960.92117236487, 36083.91748247557, 25490.617640735032, 29440.55839162457, 30682.232944176576, 31165.32924533415, 31774.841125794224, 20573.279935202198, 22393.552128957657, 22595.322140335815, 23768.762312376653, 23779.405514861573], "value": 17626.99163441558, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.96883570182, 395.62020460358036, 416.8791050595328, 420.0988878854856, 426.91461707658476, 13097.67255818605, 14949.925250373746, 20908.581828363425, 21075.789242107592, 21120.0, 17330.393436229733, 19651.70522442161, 21294.467638309052, 21692.457688557755, 22465.989030493627, 6427.935720642804, 6554.672266386698, 6688.464922806188, 6779.389854913057, 6864.382205601495, 4130.8554200602985, 4361.629261512775, 6645.368689974444, 6779.898301525481, 6783.864322713543, 7394.260573942616, 7741.61291935401, 7760.370322232338, 7776.105747838986, 7787.415943804209, 6337.746490140412, 7447.4786764926475, 7776.805579860492, 7777.961110194469, 7782.416318776114, 7258.927410725878, 7491.962540187319, 7521.05986751656, 7551.660175292125, 7581.166071732105, 27188.728112719014, 27451.45097098047, 27731.584026239623, 27772.30569235765, 27883.0, 2828.420173864353, 2884.4375346807383, 2896.985515072432, 2935.911922642324, 2938.294809245787, 15815.367385304575, 15889.616557516349, 16052.678946421007, 16177.919110404491, 16410.671786564322, 1979.8020197980231, 1981.5838673878502, 1989.333573252962, 1999.3701983875033, 2017.273781438681, 149113.0, 152539.0, 153260.2336988319, 153823.23088384597, 156527.520871869, 17932.454654051176, 20390.184392624127, 21675.891620541795, 21897.452563686034, 22175.00212490442, 35925.82037089798, 36489.635103649154, 36703.265934681156, 36742.0, 36788.89633311005, 10255.538500767483, 10321.993832990993, 10527.578896844194, 10541.367517948947, 10716.839247411295, 8391.370647201484, 8399.454035487674, 8406.78983025417, 8605.526696031653, 8613.397062205711, 10528.894711052944, 10619.415932123653, 10624.096951759188, 10772.175877563508, 10815.405152716676, 3525.206828463575, 4071.6131967463634, 4233.322668373045, 4239.448871646728, 4239.579740786871, 869.6434461870602, 876.7369789063256, 884.38093334666, 891.3003292415552, 891.4072142025481, 22418.439539011655, 22821.31536053888, 23145.53708925812, 23182.42043948915, 23185.53628927412, 907.7866701325183, 959.129697090676, 962.5957098018849, 974.7027156717128, 976.5507866381402, 10105.595776169019, 10308.742281442874, 11838.644840654793, 11959.760804783855, 12013.819792703116, 626453.0, 629740.0, 630836.0, 647471.8792181211, 650894.0, 120306.0, 142524.0, 142958.0, 145512.0, 153240.2337988303, 1152.5620264299512, 1280.2318608834626, 1344.8655134486473, 1345.165997081796, 1347.5755137131773, 19135.543432647635, 19936.84168448817, 20052.0, 20094.698579521028, 20099.196032159136, 43830.561694382646, 55665.0, 56639.6016239027, 58150.709246453494, 59449.702751485966, 82346.58826705828, 82982.0, 83331.0, 84543.57728211566, 85890.0, 11731.41342932867, 11780.58767943152, 11812.76374472523, 12141.817872732092, 12315.07636927252, 12783.744325113259, 13356.0, 13365.799513007507, 13621.318934053455, 13775.931120344734, 9702.0, 11495.0229230513, 11513.733489316, 11515.372657612825, 11607.477663505331, 11213.60752373663, 11375.886241137814, 11418.600348987746, 11448.3703396314, 11797.88202117968, 9810.362326448758, 11475.655730328104, 11720.472578734149, 11726.120540959633, 11937.641870743892, 11703.356315402734, 11717.589884354007, 12007.519699211934, 12068.0, 12077.335746534025, 11371.48828302745, 11539.307641541534, 11680.182387232811, 11728.0, 11823.881761182622], "value": 11604.958607711109, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.431591573581, 1443.0547991065855, 1535.6007438066106, 1586.5954181683671, 1609.9517014489566, 10067.09396154346, 10495.055445009957, 10495.370277783339, 10496.842547361786, 10500.684979450609, 10340.121089707383, 10398.844017339747, 10777.0, 10777.407242601654, 10816.242862999594, 2344.0858065354482, 2383.7854593086618, 2384.6423036544484, 2387.761223877607, 2388.713354397466, 2263.9434014149697, 2296.230762694491, 2297.2074634251157, 2303.170858490942, 2305.5965206088867, 2324.883755812211, 2334.6264597664376, 2344.542814151241, 2353.176388264111, 2354.234873666057, 2299.4021554395977, 2315.097112126272, 2323.314622186459, 2329.1032952313312, 2337.357226762637, 2248.898799554024, 2292.6904867842795, 2301.5627030864084, 2302.804261637763, 2305.7924786769104, 10438.0, 10515.52680129396, 10523.894761052368, 10534.947325263402, 10546.261761676686, 5630.408807075239, 5838.182654428383, 5863.091220860731, 5863.76544938202, 5879.118132280149, 3304.8512816923294, 3378.1554611347165, 3550.1124718820297, 3599.42409214527, 3603.621619729917, 986.2356673578034, 1090.983635245472, 1195.4919159357325, 1211.7818792617325, 1225.9693507662291, 13588.184708917497, 13990.580282591436, 14006.159630422208, 14828.332725027292, 14955.775663365059, 4758.95241047592, 4800.0, 4989.625778066587, 4994.9750251248515, 4999.125153098194, 12493.375331233583, 12606.684832879251, 12612.873871261354, 12614.243145411305, 12634.0, 5024.949750502448, 5205.505476979681, 5213.374395072616, 5862.267216597926, 5938.317093534212, 4539.387182730356, 4616.815327386934, 4620.306953956866, 5037.4458809530925, 5205.9219111713355, 3389.81356025416, 3973.304671682444, 4013.237484877864, 4033.7579745214794, 4073.816678249486, 1130.813415786386, 1136.9875320682345, 1143.4740019590915, 1160.8316794064813, 1167.497975870384, 923.8383282925461, 924.6440120553501, 946.2051876423837, 959.8512230604267, 1005.2912696548915, 6128.448439640454, 6284.968575157186, 6545.5090868184125, 6679.966600166968, 6800.761973330909, 1006.1347241372414, 1015.3045164062493, 1017.9006672793365, 1022.2895087913829, 1036.1451802263164, 3748.5314335708035, 3752.9249415011545, 4128.91742165155, 4273.55127711592, 4284.186004659105, 12634.747305053848, 13065.934670326587, 13065.934670326778, 13081.607551773463, 13109.475620975054, 12402.875971240353, 12877.969762418985, 13111.2133272004, 13117.278549679859, 13215.273159976101, 9506.096920792514, 9632.518374081408, 9794.510274486247, 9914.40513569188, 9928.602855885829, 5232.372115346183, 6327.0, 6617.8014659560295, 6627.502937279627, 6716.496262780213, 11572.6528204154, 11735.941320293345, 11777.941110294394, 11779.88220117788, 11780.0, 10038.949805250926, 11674.066074713832, 11699.473523691284, 11964.341961192218, 12162.574309899112, 8583.914160858312, 8611.82776344482, 8680.696175634075, 8764.474131552128, 8784.692535761218, 9624.807503850025, 9645.37305075168, 9720.222382209548, 9737.221022318055, 9741.561629726537, 8667.956660216658, 8730.476171429735, 8732.083131271125, 8757.562121894007, 8868.33487488428, 8369.163083691825, 8504.44721093127, 8534.78663033429, 8556.828863422572, 8570.442921210102, 7508.699652013858, 7665.808354791175, 7667.194944530744, 7677.8464430709955, 7685.769426917202, 5555.805546805843, 5592.944070559242, 5751.482366587027, 5798.072308430631, 5833.183354330482, 5644.8306550803545, 5681.602287839812, 5731.426857314235, 5854.209681693087, 5917.112433135064], "value": 5012.464051032197, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.78378540446545, 378.1680303332668, 388.79005337117957, 389.21573030343865, 391.60056742123044, 5376.8118115865955, 5795.304563452381, 5896.911546326808, 5914.6155499892475, 5954.910676339859, 4310.288802347617, 5225.111731005729, 5280.0, 5675.460831221038, 5769.740361683724, 4544.750038747876, 4796.544328288807, 4799.7360145192, 4800.711957282557, 4836.613070954315, 4394.362817391477, 4426.358178064179, 4800.135975524404, 4826.396700412449, 4830.879228019293, 4669.276262179351, 4710.670253082286, 4793.4487533933525, 4807.807687692488, 4812.0, 4049.2711311963835, 4231.984323762305, 4362.476502819682, 4760.0, 4775.761211939406, 4189.895252618679, 4342.261815491374, 4361.672874534423, 4371.737695738266, 4407.757573333465, 5091.949080509185, 5449.7275136243425, 5575.804846830382, 5674.404187560329, 5704.828855134353, 5512.7122244268585, 5519.806806761784, 5525.281713377275, 5530.751116199741, 5539.279893613804, 4863.708177509361, 5179.756858354006, 6130.29501607312, 6211.161493198406, 6229.972054610984, 4206.852760153411, 4624.606908412756, 4858.222684370519, 4885.43817460993, 4892.8532144035735, 5093.694378337312, 6206.882761102999, 6368.299487056373, 6375.2987171411105, 6400.9039864402075, 4060.4924384451942, 4254.851080212177, 4751.1447939370555, 4894.583960363338, 4933.260010998379, 4899.265110233494, 4972.453030166679, 5079.720615366116, 5084.237364395371, 5110.97444512775, 4735.857924262277, 5072.594192464594, 5410.213739923943, 5471.0, 5505.917411238835, 5373.543248823816, 5450.5367043801725, 6126.663033533198, 6157.84605384869, 6167.290761562478, 1555.6033211530987, 1623.553522781221, 1745.240820243173, 1767.5492749349091, 1770.4246120010791, 1099.6920862158609, 1128.4583399968062, 1246.7568824079399, 1293.6377814212035, 1307.3528603341217, 5752.395998420106, 5769.336526299446, 5774.422557744192, 5777.711114444261, 5780.797672081457, 5698.717788497638, 6278.968605156945, 6388.297287298394, 6417.358264173546, 6441.806745797634, 4988.326575912278, 5371.401161221991, 5607.0, 5704.914426283609, 5795.478406943311, 3286.8520916558814, 3312.4368857294317, 3322.7674062815854, 3329.467285234351, 3333.6833000865363, 4917.606591472673, 6031.698415079229, 6556.868862622721, 6584.604923704593, 6644.634545100066, 5542.528885044736, 6489.091527186198, 6514.674266286666, 6527.2167339919515, 6593.076969224312, 5974.581779275496, 6329.810105696836, 6561.80314590563, 6573.934260657428, 6578.967105164539, 6340.4927605792345, 6397.232332120176, 6400.647964362005, 6422.42198202164, 6517.837054073686, 5458.344998600034, 5471.425500322568, 5969.955257829775, 6128.763001504508, 6354.586951848115, 4900.215965445512, 5761.48146666802, 6608.537402381787, 6610.975298828689, 6653.168353955755, 4499.977500112478, 4756.23900175984, 4816.20532612115, 5047.167217409234, 5064.316317297118, 5543.972280138573, 6127.295361033538, 6356.650384228912, 6413.486921046212, 6452.580582262138, 5347.491988261109, 5513.558915286848, 5642.435756424489, 5671.234383358359, 6090.025595904634, 5405.351357837085, 5545.7781688732, 6196.349383314687, 6221.471174949999, 6242.469390101892, 5725.742341594721, 5992.280926288873, 6030.698465076816, 6076.210092687922, 6096.634201947897, 5489.984352894728, 5624.0, 6173.629582225081, 6194.752209911553, 6212.627242365473, 5145.125328694056, 5599.440055994368, 6044.365341639064, 6081.6655083969035, 6084.908726369196], "value": 4678.426585718856, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.9302873198315, 725.9927400725991, 727.487121579287, 782.593051613161, 848.0883050720475, 14930.1788401638, 15122.621934451618, 15680.215989200551, 15721.84278157218, 15950.601234969132, 14246.501372451967, 14305.427782888699, 14341.498047568337, 14343.56969290923, 14360.497382591611, 922.5110691333604, 926.5645146781002, 944.1738478831023, 944.2870632672323, 946.7964387656652, 951.8191543606692, 952.6618050592044, 953.4851180362596, 954.2938225712957, 955.5413401567251, 927.956049444375, 992.161623428202, 1042.681981995491, 1043.8642976413055, 1044.263794025214, 1031.468793571315, 1034.9223808214376, 1040.0847254416142, 1040.6409788622914, 1042.9778816759617, 774.4307933668749, 774.7753151586033, 775.0621747685308, 775.7556369743569, 778.0663204155037, 15735.0, 16052.197390130446, 16636.33454661812, 16663.416780412626, 16741.162941852857, 14417.207053612046, 14675.926620366936, 14764.409423623043, 14800.851991480164, 14805.333759980827, 11599.0, 11761.470733817, 11904.0, 11954.760904781855, 12243.510259589606, 3690.7231957603285, 3733.8133093345223, 3847.2690188864026, 3893.0, 3906.785126818024, 109522.45238773835, 109676.90323096747, 109793.0, 111527.44236278848, 111606.0, 10861.13110951122, 11093.944530277297, 11117.666470005914, 11244.66266012021, 11321.71695707614, 24087.638685419734, 24418.0, 24537.877310613334, 24553.87723061409, 24561.877190613934, 7732.265434783687, 7822.726204582812, 7832.138464768871, 7906.130325664172, 8105.432619716681, 4226.1336426032585, 4256.360811506118, 4313.61177494027, 4316.827326906951, 4489.438820147481, 3013.4877070898, 3429.6570342965506, 3521.436570148764, 3528.788272703646, 3601.603823579404, 569.3111335284269, 655.4494224851053, 663.5587334422555, 670.1020632352671, 683.2108914204102, 629.9275583307887, 676.3811112831802, 685.8032732881154, 687.7902239816904, 691.8892977123636, 9561.282903782245, 10009.299349045643, 10138.239632027628, 10148.746281343025, 10164.33931794431, 510.3951817096678, 545.7080461952836, 555.2254604826245, 569.5272923473507, 575.6718670357856, 4694.2958556216845, 4884.413870335511, 5012.749362531858, 5012.974935125301, 5019.347484826949, 90678.09321906828, 90937.54531227301, 92278.15443691089, 93181.0, 94146.0, 83183.0, 84405.57797211097, 84622.57688711517, 84834.0, 85832.57083714541, 53771.0, 71964.64017679878, 76846.6157669208, 77502.0, 78505.0, 6843.794686159423, 9558.95220523893, 9599.376040557341, 9606.759831004001, 9649.903500965182, 48445.54663360105, 50522.0, 50734.0, 51587.48412515977, 52003.0, 63383.0, 64790.67604661947, 65222.0, 65469.672651636436, 65749.0, 11499.0, 11576.594819181288, 11584.710382240512, 11636.94181529087, 12538.373081345713, 12685.873141268468, 12776.787482669615, 12788.360581970679, 12891.613251602466, 12982.0, 11755.471003804678, 11807.003021810988, 11824.822627660764, 11840.348780817138, 11991.796236796634, 11645.301281923112, 11815.519038872133, 11819.645410637695, 11820.763584728085, 12317.07621928341, 11680.0, 11698.94150529276, 11714.531418743154, 11810.17328787011, 11829.526818927146, 10032.64885728996, 10200.489975501047, 10201.897981020094, 10372.325798823053, 10799.244052916221, 6732.899006515004, 9172.83537179672, 9765.535169724599, 10204.693859184237, 10228.335158214692], "value": 7422.010325366547, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1476.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 116.0, 116.0, 115.0, 114.0, 115.0, 116.0, 115.0, 116.0, 115.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 109.0, 147.0, 95.0, 148.0, 138.0, 143.0, 134.0, 96.0], "value": 126.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 183.0, 185.0, 182.0, 185.0, 189.0, 172.0, 249.0, 263.0], "value": 197.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 89.0, 63.0, 62.0, 60.0, 57.0, 77.0, 59.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 87.0, 70.0, 69.0, 71.0, 85.0, 69.0, 69.0, 83.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 88.0, 88.0, 103.0, 134.0, 135.0, 88.0, 88.0], "value": 98.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 101.0, 147.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 100.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 83.0, 82.0, 83.0, 86.0, 85.0, 84.0, 84.0, 96.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 60.0, 62.0, 64.0, 62.0, 62.0, 62.0, 62.0, 63.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 61.0, 70.0, 56.0, 65.0, 61.0, 58.0, 59.0, 60.0, 59.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 86.0, 83.0, 81.0, 82.0, 80.0, 82.0, 83.0, 81.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 142.0, 145.0, 136.0, 136.0, 142.0, 136.0, 138.0, 176.0, 158.0], "value": 144.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 186.0, 181.0, 185.0, 182.0, 186.0, 180.0, 184.0, 209.0, 229.0], "value": 189.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 66.0, 71.0, 80.0, 67.0, 69.0, 69.0, 80.0], "value": 70.3, "unit": "score"}]}]} 11:28:26 INFO - Return code: 0 11:28:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:28:26 ERROR - # TBPL SUCCESS # 11:28:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:28:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:28:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:28:26 INFO - 2017-03-17 11:28:26.894 screenresolution[1650:9868] starting screenresolution argv=screenresolution get 11:28:26 INFO - 2017-03-17 11:28:26.898 screenresolution[1650:9868] Display 0: 1600x1200x32@60 11:28:26 INFO - 2017-03-17 11:28:26.905 screenresolution[1651:9871] starting screenresolution argv=screenresolution list 11:28:26 INFO - Available Modes on Display 0 11:28:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:28:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:28:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:28:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:28:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:28:27 INFO - Intel Iris: 11:28:27 INFO - Chipset Model: Intel Iris 11:28:27 INFO - Type: GPU 11:28:27 INFO - Bus: Built-In 11:28:27 INFO - VRAM (Dynamic, Max): 1536 MB 11:28:27 INFO - Vendor: Intel (0x8086) 11:28:27 INFO - Device ID: 0x0a2e 11:28:27 INFO - Revision ID: 0x0009 11:28:27 INFO - Displays: 11:28:27 INFO - Display: 11:28:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:28:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:28:27 INFO - Display Serial Number: 1600x1200 60 11:28:27 INFO - Main Display: Yes 11:28:27 INFO - Mirror: Off 11:28:27 INFO - Online: Yes 11:28:27 INFO - Rotation: Supported 11:28:27 INFO - Return code: 0 11:28:27 INFO - Running post-action listener: _resource_record_post_action 11:28:27 INFO - [mozharness: 2017-03-17 18:28:27.148165Z] Finished run-tests step (success) 11:28:27 INFO - Running post-run listener: _resource_record_post_run 11:28:27 INFO - Total resource usage - Wall time: 1202s; CPU: 53.0%; Read bytes: 38769664; Write bytes: 514028544; Read time: 376; Write time: 1626 11:28:27 INFO - TinderboxPrint: CPU usage
53.4% 11:28:27 INFO - TinderboxPrint: I/O read bytes / time
38,769,664 / 376 11:28:27 INFO - TinderboxPrint: I/O write bytes / time
514,028,544 / 1,626 11:28:27 INFO - TinderboxPrint: CPU idle
2,242.5 (46.6%) 11:28:27 INFO - TinderboxPrint: CPU system
958.7 (19.9%) 11:28:27 INFO - TinderboxPrint: CPU user
1,608.5 (33.4%) 11:28:27 INFO - TinderboxPrint: Swap in / out
248,471,552 / 0 11:28:27 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 206750720; Write bytes: 204982272; Read time: 15524; Write time: 426 11:28:27 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 31452160; Write bytes: 298236928; Read time: 289; Write time: 1166 11:28:27 INFO - Running post-run listener: _upload_blobber_files 11:28:27 INFO - Blob upload gear active. 11:28:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:28:27 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 11:28:27 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:28:27 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:28:28 INFO - (blobuploader) - INFO - Open directory for files ... 11:28:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:28:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:28:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:28:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:28:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:28:29 INFO - (blobuploader) - INFO - Done attempting. 11:28:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:28:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:28:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:28:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:28:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:28:30 INFO - (blobuploader) - INFO - Done attempting. 11:28:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:28:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:28:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:28:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:28:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:28:31 INFO - (blobuploader) - INFO - Done attempting. 11:28:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:28:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:28:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:28:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:28:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:28:33 INFO - (blobuploader) - INFO - Done attempting. 11:28:33 INFO - (blobuploader) - INFO - Iteration through files over. 11:28:33 INFO - Return code: 0 11:28:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:28:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:28:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e0b48c1cebb7ddea5e83bd9abdf8b8e5c51f9cdedfeb363c5147f577ea47b541148070ef22b00452f73940ee580226edb8649a7f53dcf425be240e724986dc15", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8786bfbe3f4777f569b35759e3e13ad856e1a378de18fb1b0b4c779a35dcaafbb5869af3effff40b40af6a991dafdc794a38656d5a7272e32dba09ecbf056805", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c6f9b80a7c47edaacb77b5d6a23f7f3a54ba0f43098beea15d68c75bf5d430d20dd9984613d8651a25dc6001eabf259d2d4cb39a07907cda6e377eb468070a3c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/30f84ea57b159137ce7bd3aa961b592af8624176d3d039b015bb55d86f729e03bb9440fea38ef695940c54564de36c2c900b24192f3dd3545c5cfbb15e6e3747"} 11:28:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:28:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:28:33 INFO - Contents: 11:28:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e0b48c1cebb7ddea5e83bd9abdf8b8e5c51f9cdedfeb363c5147f577ea47b541148070ef22b00452f73940ee580226edb8649a7f53dcf425be240e724986dc15", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8786bfbe3f4777f569b35759e3e13ad856e1a378de18fb1b0b4c779a35dcaafbb5869af3effff40b40af6a991dafdc794a38656d5a7272e32dba09ecbf056805", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c6f9b80a7c47edaacb77b5d6a23f7f3a54ba0f43098beea15d68c75bf5d430d20dd9984613d8651a25dc6001eabf259d2d4cb39a07907cda6e377eb468070a3c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/30f84ea57b159137ce7bd3aa961b592af8624176d3d039b015bb55d86f729e03bb9440fea38ef695940c54564de36c2c900b24192f3dd3545c5cfbb15e6e3747"} 11:28:33 INFO - Running post-run listener: copy_logs_to_upload_dir 11:28:33 INFO - Copying logs to upload dir... 11:28:33 INFO - mkdir: /builds/slave/test/build/upload/logs 11:28:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.796506 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-17 11:28:33.292478) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-17 11:28:33.295974) ========= 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.NLAkXL4yuk/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.LgejRIgenJ/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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/Pine/sha512/e0b48c1cebb7ddea5e83bd9abdf8b8e5c51f9cdedfeb363c5147f577ea47b541148070ef22b00452f73940ee580226edb8649a7f53dcf425be240e724986dc15", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8786bfbe3f4777f569b35759e3e13ad856e1a378de18fb1b0b4c779a35dcaafbb5869af3effff40b40af6a991dafdc794a38656d5a7272e32dba09ecbf056805", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c6f9b80a7c47edaacb77b5d6a23f7f3a54ba0f43098beea15d68c75bf5d430d20dd9984613d8651a25dc6001eabf259d2d4cb39a07907cda6e377eb468070a3c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/30f84ea57b159137ce7bd3aa961b592af8624176d3d039b015bb55d86f729e03bb9440fea38ef695940c54564de36c2c900b24192f3dd3545c5cfbb15e6e3747"} build_url:https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011188 build_url: 'https://queue.taskcluster.net/v1/task/OHAknvmkR8OOpnrPUaOiWw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e0b48c1cebb7ddea5e83bd9abdf8b8e5c51f9cdedfeb363c5147f577ea47b541148070ef22b00452f73940ee580226edb8649a7f53dcf425be240e724986dc15", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8786bfbe3f4777f569b35759e3e13ad856e1a378de18fb1b0b4c779a35dcaafbb5869af3effff40b40af6a991dafdc794a38656d5a7272e32dba09ecbf056805", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c6f9b80a7c47edaacb77b5d6a23f7f3a54ba0f43098beea15d68c75bf5d430d20dd9984613d8651a25dc6001eabf259d2d4cb39a07907cda6e377eb468070a3c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/30f84ea57b159137ce7bd3aa961b592af8624176d3d039b015bb55d86f729e03bb9440fea38ef695940c54564de36c2c900b24192f3dd3545c5cfbb15e6e3747"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-17 11:28:33.334119) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 11:28:33.334465) ========= 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.NLAkXL4yuk/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.LgejRIgenJ/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.005219 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 11:28:33.383431) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-17 11:28:33.383752) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-17 11:28:33.384096) ========= ========= Total master_lag: 0.20 =========