builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0299 starttime: 1484243715.66 results: success (0) buildid: 20170112072816 builduid: 08aa67cc47c0432e8329d5f7e4ead3d2 revision: 7d6605552545628e8a99882802f7d7695a64290c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 09:55:15.657043) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 09:55:15.657490) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 09:55:15.657773) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 09:55:15.687038) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 09:55:15.687392) ========= 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.ewectKOCOV/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.ZiLLZpcoq8/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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-01-12 09:55:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2017-01-12 09:55:15 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.098795 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 09:55:15.809736) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 09:55:15.810043) ========= 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.ewectKOCOV/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.ZiLLZpcoq8/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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.027650 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 09:55:15.863642) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-12 09:55:15.863997) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7d6605552545628e8a99882802f7d7695a64290c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7d6605552545628e8a99882802f7d7695a64290c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ewectKOCOV/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.ZiLLZpcoq8/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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-01-12 09:55:15,966 truncating revision to first 12 chars 2017-01-12 09:55:15,966 Setting DEBUG logging. 2017-01-12 09:55:15,966 attempt 1/10 2017-01-12 09:55:15,966 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/7d6605552545?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-12 09:55:17,530 unpacking tar archive at: mozilla-beta-7d6605552545/testing/mozharness/ program finished with exit code 0 elapsedTime=1.846193 ========= master_lag: 0.85 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-12 09:55:18.557248) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 09:55:18.557534) ========= script_repo_revision: 7d6605552545628e8a99882802f7d7695a64290c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 09:55:18.557888) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 09:55:18.558154) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 09:55:18.583442) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-01-12 09:55:18.586768) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ewectKOCOV/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.ZiLLZpcoq8/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:55:18 INFO - MultiFileLogger online at 20170112 09:55:18 in /builds/slave/test 09:55:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:55:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:55:18 INFO - {'append_to_log': False, 09:55:18 INFO - 'base_work_dir': '/builds/slave/test', 09:55:18 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:55:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:55:18 INFO - 'branch': 'Mozilla-Beta', 09:55:18 INFO - 'buildbot_json_path': 'buildprops.json', 09:55:18 INFO - 'config_files': ('talos/mac_config.py',), 09:55:18 INFO - 'default_actions': ('clobber', 09:55:18 INFO - 'read-buildbot-config', 09:55:18 INFO - 'download-and-extract', 09:55:18 INFO - 'populate-webroot', 09:55:18 INFO - 'create-virtualenv', 09:55:18 INFO - 'install', 09:55:18 INFO - 'run-tests'), 09:55:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:55:18 INFO - 'download_minidump_stackwalk': True, 09:55:18 INFO - 'download_symbols': 'ondemand', 09:55:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:55:18 INFO - 'tooltool.py': '/tools/tooltool.py', 09:55:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:55:18 INFO - '/tools/misc-python/virtualenv.py')}, 09:55:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:55:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:55:18 INFO - 'installer_path': 'installer.exe', 09:55:18 INFO - 'log_level': 'info', 09:55:18 INFO - 'log_name': 'talos', 09:55:18 INFO - 'log_to_console': True, 09:55:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:55:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:55:18 INFO - 'opt_config_files': (), 09:55:18 INFO - 'pip_index': False, 09:55:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:55:18 INFO - 'cmd': ('bash', 09:55:18 INFO - '-c', 09:55:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:55:18 INFO - 'enabled': True, 09:55:18 INFO - 'halt_on_failure': False, 09:55:18 INFO - 'name': 'check_screen_resolution'},), 09:55:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:55:18 INFO - 'cmd': ('bash', 09:55:18 INFO - '-c', 09:55:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:55:18 INFO - 'enabled': True, 09:55:18 INFO - 'halt_on_failure': False, 09:55:18 INFO - 'name': 'check_screen_resolution'},), 09:55:18 INFO - 'run_cmd_checks_enabled': True, 09:55:18 INFO - 'sps_profile': False, 09:55:18 INFO - 'sps_profile_interval': 0, 09:55:18 INFO - 'suite': 'dromaeojs-e10s', 09:55:18 INFO - 'system_bits': '32', 09:55:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:55:18 INFO - 'title': 't-yosemite-r7-0299', 09:55:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:55:18 INFO - 'use_talos_json': True, 09:55:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:55:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:55:18 INFO - 'work_dir': 'build'} 09:55:18 INFO - [mozharness: 2017-01-12 17:55:18.796769Z] Running clobber step. 09:55:18 INFO - Running pre-action listener: _resource_record_pre_action 09:55:18 INFO - Running main action method: clobber 09:55:18 INFO - rmtree: /builds/slave/test/build 09:55:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:55:21 INFO - Running post-action listener: _resource_record_post_action 09:55:21 INFO - [mozharness: 2017-01-12 17:55:21.043820Z] Finished clobber step (success) 09:55:21 INFO - [mozharness: 2017-01-12 17:55:21.043984Z] Running read-buildbot-config step. 09:55:21 INFO - Running pre-action listener: _resource_record_pre_action 09:55:21 INFO - Running main action method: read_buildbot_config 09:55:21 INFO - Using buildbot properties: 09:55:21 INFO - { 09:55:21 INFO - "project": "", 09:55:21 INFO - "product": "firefox", 09:55:21 INFO - "script_repo_revision": "production", 09:55:21 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 09:55:21 INFO - "repository": "", 09:55:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 09:55:21 INFO - "buildid": "20170112072816", 09:55:21 INFO - "pgo_build": "False", 09:55:21 INFO - "basedir": "/builds/slave/test", 09:55:21 INFO - "buildnumber": 30, 09:55:21 INFO - "slavename": "t-yosemite-r7-0299", 09:55:21 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:55:21 INFO - "platform": "yosemite_r7", 09:55:21 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:55:21 INFO - "branch": "mozilla-beta", 09:55:21 INFO - "repo_path": "releases/mozilla-beta", 09:55:21 INFO - "revision": "7d6605552545628e8a99882802f7d7695a64290c", 09:55:21 INFO - "stage_platform": "macosx64", 09:55:21 INFO - "builduid": "08aa67cc47c0432e8329d5f7e4ead3d2", 09:55:21 INFO - "slavebuilddir": "test" 09:55:21 INFO - } 09:55:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 09:55:21 INFO - Running post-action listener: _resource_record_post_action 09:55:21 INFO - [mozharness: 2017-01-12 17:55:21.047322Z] Finished read-buildbot-config step (success) 09:55:21 INFO - [mozharness: 2017-01-12 17:55:21.047452Z] Running download-and-extract step. 09:55:21 INFO - Running pre-action listener: _resource_record_pre_action 09:55:21 INFO - Running main action method: download_and_extract 09:55:21 INFO - mkdir: /builds/slave/test/build/tests 09:55:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:21 INFO - trying https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 09:55:21 INFO - Downloading https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 09:55:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json'}, attempt #1 09:55:22 INFO - Downloaded 1165 bytes. 09:55:22 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 09:55:22 INFO - Using the following test package requirements: 09:55:22 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 09:55:22 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:55:22 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 09:55:22 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:55:22 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 09:55:22 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:55:22 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:55:22 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 09:55:22 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 09:55:22 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:55:22 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 09:55:22 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:55:22 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 09:55:22 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:55:22 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 09:55:22 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:55:22 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 09:55:22 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 09:55:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 09:55:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 09:55:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 09:55:22 INFO - Expected file size: 16236106 09:55:29 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip', u'firefox-51.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:55:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 09:55:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 09:55:29 INFO - Fetch https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 09:55:30 INFO - Expected file size: 16236106 09:55:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 09:55:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 09:55:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 09:55:37 INFO - Expected file size: 14414089 09:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:38 INFO - trying https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.dmg 09:55:38 INFO - Downloading https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 09:55:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg'}, attempt #1 09:55:40 INFO - Downloaded 86144322 bytes. 09:55:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.dmg 09:55:40 INFO - mkdir: /builds/slave/test/properties 09:55:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:55:40 INFO - Writing to file /builds/slave/test/properties/build_url 09:55:40 INFO - Contents: 09:55:40 INFO - build_url:https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.dmg 09:55:41 INFO - Running post-action listener: _resource_record_post_action 09:55:41 INFO - Running post-action listener: set_extra_try_arguments 09:55:41 INFO - [mozharness: 2017-01-12 17:55:41.590253Z] Finished download-and-extract step (success) 09:55:41 INFO - [mozharness: 2017-01-12 17:55:41.590400Z] Running populate-webroot step. 09:55:41 INFO - Running pre-action listener: _resource_record_pre_action 09:55:41 INFO - Running main action method: populate_webroot 09:55:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:55:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:55:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:55:41 INFO - '--tppagecycles', 09:55:41 INFO - '7'], 09:55:41 INFO - 'tests': ['tsvgm']}}, 09:55:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:55:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:55:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:55:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:55:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:55:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:55:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:41 INFO - 'talos_options': ['--disable-e10s'], 09:55:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:55:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:55:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:41 INFO - 'talos_options': ['--disable-e10s'], 09:55:41 INFO - 'tests': ['damp', 'tps']}, 09:55:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:41 INFO - 'tests': ['damp', 'tps']}, 09:55:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:55:41 INFO - 'tests': ['dromaeo_dom']}, 09:55:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:55:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:55:41 INFO - 'tests': ['basic_compositor_video']}, 09:55:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:55:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:55:41 INFO - 'tests': ['a11yr', 09:55:41 INFO - 'ts_paint', 09:55:41 INFO - 'tpaint', 09:55:41 INFO - 'sessionrestore', 09:55:41 INFO - 'sessionrestore_no_auto_restore', 09:55:41 INFO - 'tabpaint']}, 09:55:41 INFO - 'other-e10s': {'tests': ['a11yr', 09:55:41 INFO - 'ts_paint', 09:55:41 INFO - 'tpaint', 09:55:41 INFO - 'sessionrestore', 09:55:41 INFO - 'sessionrestore_no_auto_restore', 09:55:41 INFO - 'tabpaint']}, 09:55:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:55:41 INFO - 'ts_paint', 09:55:41 INFO - 'tpaint', 09:55:41 INFO - 'sessionrestore', 09:55:41 INFO - 'sessionrestore_no_auto_restore', 09:55:41 INFO - 'tabpaint']}, 09:55:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:55:41 INFO - 'ts_paint', 09:55:41 INFO - 'tpaint', 09:55:41 INFO - 'sessionrestore', 09:55:41 INFO - 'sessionrestore_no_auto_restore', 09:55:41 INFO - 'tabpaint']}, 09:55:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:55:41 INFO - 'tests': ['a11yr', 09:55:41 INFO - 'ts_paint', 09:55:41 INFO - 'tpaint', 09:55:41 INFO - 'sessionrestore', 09:55:41 INFO - 'sessionrestore_no_auto_restore', 09:55:41 INFO - 'tabpaint']}, 09:55:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:55:41 INFO - 'tests': ['a11yr', 09:55:41 INFO - 'ts_paint', 09:55:41 INFO - 'tpaint', 09:55:41 INFO - 'sessionrestore', 09:55:41 INFO - 'sessionrestore_no_auto_restore', 09:55:41 INFO - 'tabpaint']}, 09:55:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:55:41 INFO - 'tests': ['tsvgx', 09:55:41 INFO - 'tsvgr_opacity', 09:55:41 INFO - 'tart', 09:55:41 INFO - 'tscrollx', 09:55:41 INFO - 'cart']}, 09:55:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:55:41 INFO - 'tsvgr_opacity', 09:55:41 INFO - 'tart', 09:55:41 INFO - 'tscrollx', 09:55:41 INFO - 'cart']}, 09:55:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:41 INFO - 'talos_options': ['--disable-e10s'], 09:55:41 INFO - 'tests': ['tp5o']}, 09:55:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:41 INFO - 'tests': ['tp5o']}, 09:55:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:41 INFO - 'talos_options': ['--disable-e10s', 09:55:41 INFO - '--xperf_path', 09:55:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:55:41 INFO - 'tests': ['tp5n']}, 09:55:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:41 INFO - 'talos_options': ['--xperf_path', 09:55:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:55:41 INFO - 'tests': ['tp5n']}}, 09:55:41 INFO - 'talos.zip': {'path': '', 09:55:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:55:41 INFO - Running post-action listener: _resource_record_post_action 09:55:41 INFO - [mozharness: 2017-01-12 17:55:41.609245Z] Finished populate-webroot step (success) 09:55:41 INFO - [mozharness: 2017-01-12 17:55:41.609320Z] Running create-virtualenv step. 09:55:41 INFO - Running pre-action listener: _resource_record_pre_action 09:55:41 INFO - Running main action method: create_virtualenv 09:55:41 INFO - Creating virtualenv /builds/slave/test/build/venv 09:55:41 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:55:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:55:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:55:41 INFO - Using real prefix '/tools/python27' 09:55:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:55:42 INFO - Installing distribute.............................................................................................................................................................................................done. 09:55:45 INFO - Installing pip.................done. 09:55:45 INFO - Return code: 0 09:55:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:55: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')]} 09:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55: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')]} 09:55:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe470495620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10756cd30>, '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 0x1073d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107564b20>, '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.ewectKOCOV/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.ZiLLZpcoq8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:55:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:55:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ewectKOCOV/Render', 09:55:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:45 INFO - 'HOME': '/Users/cltbld', 09:55:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:45 INFO - 'LOGNAME': 'cltbld', 09:55:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:45 INFO - 'MOZ_NO_REMOTE': '1', 09:55:45 INFO - 'NO_EM_RESTART': '1', 09:55:45 INFO - 'PAGER': '/bin/cat', 09:55:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:45 INFO - 'PWD': '/builds/slave/test', 09:55:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:45 INFO - 'SHELL': '/bin/bash', 09:55:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiLLZpcoq8/Listeners', 09:55:45 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 09:55:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:45 INFO - 'USER': 'cltbld', 09:55:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:45 INFO - 'XPC_FLAGS': '0x0', 09:55:45 INFO - 'XPC_SERVICE_NAME': '0', 09:55:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:45 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:55:45 INFO - Cleaning up... 09:55:45 INFO - Return code: 0 09:55:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:55: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')]} 09:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55: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')]} 09:55:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe470495620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10756cd30>, '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 0x1073d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107564b20>, '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.ewectKOCOV/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.ZiLLZpcoq8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:55:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:55:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ewectKOCOV/Render', 09:55:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:45 INFO - 'HOME': '/Users/cltbld', 09:55:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:45 INFO - 'LOGNAME': 'cltbld', 09:55:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:45 INFO - 'MOZ_NO_REMOTE': '1', 09:55:45 INFO - 'NO_EM_RESTART': '1', 09:55:45 INFO - 'PAGER': '/bin/cat', 09:55:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:45 INFO - 'PWD': '/builds/slave/test', 09:55:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:45 INFO - 'SHELL': '/bin/bash', 09:55:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiLLZpcoq8/Listeners', 09:55:45 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 09:55:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:45 INFO - 'USER': 'cltbld', 09:55:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:45 INFO - 'XPC_FLAGS': '0x0', 09:55:45 INFO - 'XPC_SERVICE_NAME': '0', 09:55:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:45 INFO - Downloading/unpacking psutil>=3.1.1 09:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:55:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:55:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:55:49 INFO - Installing collected packages: psutil 09:55:49 INFO - Running setup.py install for psutil 09:55:49 INFO - building 'psutil._psutil_osx' extension 09:55:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:55:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:55:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:55:50 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:55:50 INFO - building 'psutil._psutil_posix' extension 09:55:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:55:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:55:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:55:50 INFO - ^ 09:55:50 INFO - 1 warning generated. 09:55:50 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:55:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:55:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:55:50 INFO - Successfully installed psutil 09:55:50 INFO - Cleaning up... 09:55:50 INFO - Return code: 0 09:55:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe470495620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10756cd30>, '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 0x1073d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107564b20>, '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.ewectKOCOV/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.ZiLLZpcoq8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:55:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ewectKOCOV/Render', 09:55:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:50 INFO - 'HOME': '/Users/cltbld', 09:55:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:50 INFO - 'LOGNAME': 'cltbld', 09:55:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:50 INFO - 'MOZ_NO_REMOTE': '1', 09:55:50 INFO - 'NO_EM_RESTART': '1', 09:55:50 INFO - 'PAGER': '/bin/cat', 09:55:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:50 INFO - 'PWD': '/builds/slave/test', 09:55:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:50 INFO - 'SHELL': '/bin/bash', 09:55:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiLLZpcoq8/Listeners', 09:55:50 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 09:55:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:50 INFO - 'USER': 'cltbld', 09:55:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:50 INFO - 'XPC_FLAGS': '0x0', 09:55:50 INFO - 'XPC_SERVICE_NAME': '0', 09:55:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:55:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:55:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:55:53 INFO - Installing collected packages: mozsystemmonitor 09:55:53 INFO - Running setup.py install for mozsystemmonitor 09:55:53 INFO - Successfully installed mozsystemmonitor 09:55:53 INFO - Cleaning up... 09:55:53 INFO - Return code: 0 09:55:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe470495620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10756cd30>, '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 0x1073d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107564b20>, '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.ewectKOCOV/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.ZiLLZpcoq8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:55:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:55:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ewectKOCOV/Render', 09:55:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:53 INFO - 'HOME': '/Users/cltbld', 09:55:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:53 INFO - 'LOGNAME': 'cltbld', 09:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:53 INFO - 'MOZ_NO_REMOTE': '1', 09:55:53 INFO - 'NO_EM_RESTART': '1', 09:55:53 INFO - 'PAGER': '/bin/cat', 09:55:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:53 INFO - 'PWD': '/builds/slave/test', 09:55:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:53 INFO - 'SHELL': '/bin/bash', 09:55:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiLLZpcoq8/Listeners', 09:55:53 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 09:55:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:53 INFO - 'USER': 'cltbld', 09:55:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:53 INFO - 'XPC_FLAGS': '0x0', 09:55:53 INFO - 'XPC_SERVICE_NAME': '0', 09:55:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:53 INFO - Downloading/unpacking jsonschema==2.5.1 09:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:55:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:56 INFO - Downloading functools32-3.2.3-2.tar.gz 09:55:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:55:57 INFO - warning: no files found matching '*.txt' 09:55:57 INFO - no previously-included directories found matching 'build' 09:55:57 INFO - no previously-included directories found matching 'dist' 09:55:57 INFO - no previously-included directories found matching '.git*' 09:55:57 INFO - Installing collected packages: jsonschema, functools32 09:55:57 INFO - Running setup.py install for functools32 09:55:57 INFO - warning: no files found matching '*.txt' 09:55:57 INFO - no previously-included directories found matching 'build' 09:55:57 INFO - no previously-included directories found matching 'dist' 09:55:57 INFO - no previously-included directories found matching '.git*' 09:55:57 INFO - Successfully installed jsonschema functools32 09:55:57 INFO - Cleaning up... 09:55:57 INFO - Return code: 0 09:55:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:55:57 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')]} 09:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:57 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')]} 09:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:57 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 0x107758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe470495620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10756cd30>, '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 0x1073d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107564b20>, '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.ewectKOCOV/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.ZiLLZpcoq8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:57 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 09:55:57 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 09:55:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ewectKOCOV/Render', 09:55:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:57 INFO - 'HOME': '/Users/cltbld', 09:55:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:57 INFO - 'LOGNAME': 'cltbld', 09:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:57 INFO - 'MOZ_NO_REMOTE': '1', 09:55:57 INFO - 'NO_EM_RESTART': '1', 09:55:57 INFO - 'PAGER': '/bin/cat', 09:55:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:57 INFO - 'PWD': '/builds/slave/test', 09:55:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:57 INFO - 'SHELL': '/bin/bash', 09:55:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiLLZpcoq8/Listeners', 09:55:57 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 09:55:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:57 INFO - 'USER': 'cltbld', 09:55:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:57 INFO - 'XPC_FLAGS': '0x0', 09:55:57 INFO - 'XPC_SERVICE_NAME': '0', 09:55:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:55:57 INFO - Cleaning up... 09:55:57 INFO - Return code: 0 09:55:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:55:57 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')]} 09:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:57 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')]} 09:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:57 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 0x107758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe470495620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10756cd30>, '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 0x1073d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107564b20>, '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.ewectKOCOV/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.ZiLLZpcoq8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:55:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:55:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ewectKOCOV/Render', 09:55:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:57 INFO - 'HOME': '/Users/cltbld', 09:55:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:57 INFO - 'LOGNAME': 'cltbld', 09:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:57 INFO - 'MOZ_NO_REMOTE': '1', 09:55:57 INFO - 'NO_EM_RESTART': '1', 09:55:57 INFO - 'PAGER': '/bin/cat', 09:55:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:57 INFO - 'PWD': '/builds/slave/test', 09:55:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:57 INFO - 'SHELL': '/bin/bash', 09:55:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiLLZpcoq8/Listeners', 09:55:57 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 09:55:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:57 INFO - 'USER': 'cltbld', 09:55:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:57 INFO - 'XPC_FLAGS': '0x0', 09:55:57 INFO - 'XPC_SERVICE_NAME': '0', 09:55:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:57 INFO - Downloading/unpacking blobuploader==1.2.4 09:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:00 INFO - Downloading blobuploader-1.2.4.tar.gz 09:56:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:56:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:56:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:56: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 09:56: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 09:56: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 09:56: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 09:56: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 09:56: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 09:56:02 INFO - Downloading docopt-0.6.1.tar.gz 09:56:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:56:02 INFO - Installing collected packages: blobuploader, requests, docopt 09:56:02 INFO - Running setup.py install for blobuploader 09:56:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:56:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:56:02 INFO - Running setup.py install for requests 09:56:02 INFO - Running setup.py install for docopt 09:56:03 INFO - Successfully installed blobuploader requests docopt 09:56:03 INFO - Cleaning up... 09:56:03 INFO - Return code: 0 09:56:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56:03 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')]} 09:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:03 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')]} 09:56:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:03 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 0x107758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe470495620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10756cd30>, '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 0x1073d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107564b20>, '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.ewectKOCOV/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.ZiLLZpcoq8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:56:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:56:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:56:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ewectKOCOV/Render', 09:56:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:03 INFO - 'HOME': '/Users/cltbld', 09:56:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:03 INFO - 'LOGNAME': 'cltbld', 09:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:03 INFO - 'MOZ_NO_REMOTE': '1', 09:56:03 INFO - 'NO_EM_RESTART': '1', 09:56:03 INFO - 'PAGER': '/bin/cat', 09:56:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:03 INFO - 'PWD': '/builds/slave/test', 09:56:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:03 INFO - 'SHELL': '/bin/bash', 09:56:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiLLZpcoq8/Listeners', 09:56:03 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 09:56:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:03 INFO - 'USER': 'cltbld', 09:56:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:03 INFO - 'XPC_FLAGS': '0x0', 09:56:03 INFO - 'XPC_SERVICE_NAME': '0', 09:56:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:56:03 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-iDWBn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:56:03 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-zUGMuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:56:04 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-eJIbsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:56:04 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-4IKt1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:56:04 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-dGBAFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:56:04 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-nKYWrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:56:04 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-f_rWMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:56:04 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-O1dplB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:56:04 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-kWOHiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:56:04 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-v8b4kY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:56:04 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-hfIF1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:56:04 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-IlUDzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:56:05 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-SCQEn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:56:05 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-D7LRSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:05 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-ixUpIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:56:05 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-s9aEB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:56:05 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-Gnjd5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:56:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:56:05 INFO - Running setup.py install for manifestparser 09:56:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:56:05 INFO - Running setup.py install for mozcrash 09:56:05 INFO - Running setup.py install for mozdebug 09:56:05 INFO - Running setup.py install for mozdevice 09:56:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:56:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:56:06 INFO - Running setup.py install for mozfile 09:56:06 INFO - Running setup.py install for mozhttpd 09:56:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:56:06 INFO - Running setup.py install for mozinfo 09:56:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:56:06 INFO - Running setup.py install for mozInstall 09:56:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:56:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:56:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:56:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:56:06 INFO - Running setup.py install for mozleak 09:56:06 INFO - Running setup.py install for mozlog 09:56:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:56:07 INFO - Running setup.py install for moznetwork 09:56:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:56:07 INFO - Running setup.py install for mozprocess 09:56:07 INFO - Running setup.py install for mozprofile 09:56:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:56:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:56:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:56:07 INFO - Running setup.py install for mozrunner 09:56:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:56:07 INFO - Running setup.py install for mozscreenshot 09:56:07 INFO - Running setup.py install for moztest 09:56:07 INFO - Running setup.py install for mozversion 09:56:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:56:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:56:08 INFO - Cleaning up... 09:56:08 INFO - Return code: 0 09:56:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56: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')]} 09:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56: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')]} 09:56:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:08 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 0x107758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe470495620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10756cd30>, '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 0x1073d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107564b20>, '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.ewectKOCOV/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.ZiLLZpcoq8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:56:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:56:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:56:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ewectKOCOV/Render', 09:56:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:08 INFO - 'HOME': '/Users/cltbld', 09:56:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:08 INFO - 'LOGNAME': 'cltbld', 09:56:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:08 INFO - 'MOZ_NO_REMOTE': '1', 09:56:08 INFO - 'NO_EM_RESTART': '1', 09:56:08 INFO - 'PAGER': '/bin/cat', 09:56:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:08 INFO - 'PWD': '/builds/slave/test', 09:56:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:08 INFO - 'SHELL': '/bin/bash', 09:56:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiLLZpcoq8/Listeners', 09:56:08 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 09:56:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:08 INFO - 'USER': 'cltbld', 09:56:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:08 INFO - 'XPC_FLAGS': '0x0', 09:56:08 INFO - 'XPC_SERVICE_NAME': '0', 09:56:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:56:08 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-1ttZkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:56:08 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-HelLWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:56:08 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-fiI9sK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:56:08 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-csudyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:56:08 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-vkQv74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:56:08 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-4uMHOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:56:09 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-t5FzOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:56:09 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-xi3eZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:56:09 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-qgmuQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:56:09 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-Su1kt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:56:09 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)) 09:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:56:09 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-yuVNRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:56:09 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-vaEeFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:56:09 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-puYfgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:56:09 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-91PkxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:09 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-8uwsgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:56:10 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-MdJfkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:56:10 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-p_CNkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:56:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:12 INFO - Downloading blessings-1.6.tar.gz 09:56:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:56:12 INFO - Installing collected packages: blessings 09:56:12 INFO - Running setup.py install for blessings 09:56:13 INFO - Successfully installed blessings 09:56:13 INFO - Cleaning up... 09:56:13 INFO - Return code: 0 09:56:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:56:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:56:13 INFO - Reading from file tmpfile_stdout 09:56:13 INFO - Current package versions: 09:56:13 INFO - blessings == 1.6 09:56:13 INFO - blobuploader == 1.2.4 09:56:13 INFO - docopt == 0.6.1 09:56:13 INFO - functools32 == 3.2.3-2 09:56:13 INFO - jsonschema == 2.5.1 09:56:13 INFO - manifestparser == 1.1 09:56:13 INFO - mozInstall == 1.12 09:56:13 INFO - mozcrash == 0.17 09:56:13 INFO - mozdebug == 0.1 09:56:13 INFO - mozdevice == 0.48 09:56:13 INFO - mozfile == 1.2 09:56:13 INFO - mozhttpd == 0.7 09:56:13 INFO - mozinfo == 0.9 09:56:13 INFO - mozleak == 0.1 09:56:13 INFO - mozlog == 3.4 09:56:13 INFO - moznetwork == 0.27 09:56:13 INFO - mozprocess == 0.23 09:56:13 INFO - mozprofile == 0.28 09:56:13 INFO - mozrunner == 6.12 09:56:13 INFO - mozscreenshot == 0.1 09:56:13 INFO - mozsystemmonitor == 0.3 09:56:13 INFO - moztest == 0.7 09:56:13 INFO - mozversion == 1.4 09:56:13 INFO - psutil == 3.1.1 09:56:13 INFO - requests == 1.2.3 09:56:13 INFO - wsgiref == 0.1.2 09:56:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56: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')]} 09:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56: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')]} 09:56:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:13 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 0x107758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe470495620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10756cd30>, '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 0x1073d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107564b20>, '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.ewectKOCOV/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.ZiLLZpcoq8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:56:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:56:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ewectKOCOV/Render', 09:56:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:13 INFO - 'HOME': '/Users/cltbld', 09:56:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:13 INFO - 'LOGNAME': 'cltbld', 09:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:13 INFO - 'MOZ_NO_REMOTE': '1', 09:56:13 INFO - 'NO_EM_RESTART': '1', 09:56:13 INFO - 'PAGER': '/bin/cat', 09:56:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:13 INFO - 'PWD': '/builds/slave/test', 09:56:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:13 INFO - 'SHELL': '/bin/bash', 09:56:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiLLZpcoq8/Listeners', 09:56:13 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 09:56:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:13 INFO - 'USER': 'cltbld', 09:56:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:13 INFO - 'XPC_FLAGS': '0x0', 09:56:13 INFO - 'XPC_SERVICE_NAME': '0', 09:56:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:56:13 INFO - Cleaning up... 09:56:13 INFO - Return code: 0 09:56:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:56: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')]} 09:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56: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')]} 09:56:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107758490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ce570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe470495620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10756cd30>, '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 0x1073d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107564b20>, '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.ewectKOCOV/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.ZiLLZpcoq8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:56:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:56:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ewectKOCOV/Render', 09:56:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:13 INFO - 'HOME': '/Users/cltbld', 09:56:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:13 INFO - 'LOGNAME': 'cltbld', 09:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:13 INFO - 'MOZ_NO_REMOTE': '1', 09:56:13 INFO - 'NO_EM_RESTART': '1', 09:56:13 INFO - 'PAGER': '/bin/cat', 09:56:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:13 INFO - 'PWD': '/builds/slave/test', 09:56:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:13 INFO - 'SHELL': '/bin/bash', 09:56:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiLLZpcoq8/Listeners', 09:56:13 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 09:56:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:13 INFO - 'USER': 'cltbld', 09:56:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:13 INFO - 'XPC_FLAGS': '0x0', 09:56:13 INFO - 'XPC_SERVICE_NAME': '0', 09:56:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:56:13 INFO - Cleaning up... 09:56:13 INFO - Return code: 0 09:56:13 INFO - Running post-action listener: _resource_record_post_action 09:56:13 INFO - Running post-action listener: _start_resource_monitoring 09:56:14 INFO - Starting resource monitoring. 09:56:14 INFO - [mozharness: 2017-01-12 17:56:14.012249Z] Finished create-virtualenv step (success) 09:56:14 INFO - [mozharness: 2017-01-12 17:56:14.013269Z] Running install step. 09:56:14 INFO - Running pre-action listener: _resource_record_pre_action 09:56:14 INFO - Running main action method: install 09:56:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:56:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:56:14 INFO - Reading from file tmpfile_stdout 09:56:14 INFO - Detecting whether we're running mozinstall >=1.0... 09:56:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:56:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:56:14 INFO - Reading from file tmpfile_stdout 09:56:14 INFO - Output received: 09:56:14 INFO - Usage: mozinstall [options] installer 09:56:14 INFO - Options: 09:56:14 INFO - -h, --help show this help message and exit 09:56:14 INFO - -d DEST, --destination=DEST 09:56:14 INFO - Directory to install application into. [default: 09:56:14 INFO - "/builds/slave/test"] 09:56:14 INFO - --app=APP Application being installed. [default: firefox] 09:56:14 INFO - mkdir: /builds/slave/test/build/application 09:56:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:56:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:56:36 INFO - Reading from file tmpfile_stdout 09:56:36 INFO - Output received: 09:56:36 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:56:36 INFO - Running post-action listener: _resource_record_post_action 09:56:36 INFO - [mozharness: 2017-01-12 17:56:36.514189Z] Finished install step (success) 09:56:36 INFO - [mozharness: 2017-01-12 17:56:36.514319Z] Running run-tests step. 09:56:36 INFO - Running pre-action listener: _resource_record_pre_action 09:56:36 INFO - Running main action method: run_tests 09:56:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:56:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:56:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:56:36 INFO - 2017-01-12 09:56:36.528 screenresolution[1629:7405] starting screenresolution argv=screenresolution get 09:56:36 INFO - 2017-01-12 09:56:36.531 screenresolution[1629:7405] Display 0: 1600x1200x32@60 09:56:36 INFO - 2017-01-12 09:56:36.537 screenresolution[1630:7407] starting screenresolution argv=screenresolution list 09:56:36 INFO - Available Modes on Display 0 09:56:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:56:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:56:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:56:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:56:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:56:36 INFO - Intel Iris: 09:56:36 INFO - Chipset Model: Intel Iris 09:56:36 INFO - Type: GPU 09:56:36 INFO - Bus: Built-In 09:56:36 INFO - VRAM (Dynamic, Max): 1536 MB 09:56:36 INFO - Vendor: Intel (0x8086) 09:56:36 INFO - Device ID: 0x0a2e 09:56:36 INFO - Revision ID: 0x0009 09:56:36 INFO - Displays: 09:56:36 INFO - Display: 09:56:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:56:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:56:36 INFO - Display Serial Number: 1600x1200 60 09:56:36 INFO - Main Display: Yes 09:56:36 INFO - Mirror: Off 09:56:36 INFO - Online: Yes 09:56:36 INFO - Rotation: Supported 09:56:36 INFO - Return code: 0 09:56:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:56:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:56:36 INFO - Python 2.7.3 09:56:36 INFO - Return code: 0 09:56:36 INFO - grabbing minidump binary from tooltool 09:56:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:36 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 0x10756cd30>, '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 0x1073d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107564b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:56:36 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:56:36 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:56:36 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 09:56:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:56:36 INFO - Return code: 0 09:56:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:56:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:56:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:56:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:56:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:56:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0299', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:56:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0299 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:56:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ewectKOCOV/Render', 09:56:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:36 INFO - 'HOME': '/Users/cltbld', 09:56:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:36 INFO - 'LOGNAME': 'cltbld', 09:56:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:56:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:56:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:36 INFO - 'MOZ_NO_REMOTE': '1', 09:56:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:56:36 INFO - 'NO_EM_RESTART': '1', 09:56:36 INFO - 'PAGER': '/bin/cat', 09:56:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:36 INFO - 'PWD': '/builds/slave/test', 09:56:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:56:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:36 INFO - 'SHELL': '/bin/bash', 09:56:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiLLZpcoq8/Listeners', 09:56:36 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 09:56:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:36 INFO - 'USER': 'cltbld', 09:56:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:36 INFO - 'XPC_FLAGS': '0x0', 09:56:36 INFO - 'XPC_SERVICE_NAME': '0', 09:56:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0299', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:56:37 INFO - mozversion application_buildid: 20170112072816 09:56:37 INFO - mozversion application_changeset: 7d6605552545628e8a99882802f7d7695a64290c 09:56:37 INFO - mozversion application_display_name: Firefox 09:56:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:56:37 INFO - mozversion application_name: Firefox 09:56:37 INFO - mozversion application_remotingname: firefox 09:56:37 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:56:37 INFO - mozversion application_vendor: Mozilla 09:56:37 INFO - mozversion application_version: 51.0 09:56:37 INFO - mozversion platform_buildid: 20170112072816 09:56:37 INFO - mozversion platform_changeset: 7d6605552545628e8a99882802f7d7695a64290c 09:56:37 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:56:37 INFO - mozversion platform_version: 51.0 09:56:37 INFO - using testdate: 1484243797 09:56:37 INFO - actual date: 1484243797 09:56:37 INFO - starting webserver on 'localhost:49256' 09:56:37 INFO - SUITE-START | Running 2 tests 09:56:37 INFO - TEST-START | dromaeo_css 09:56:37 INFO - operating with platform_type : mac_ 09:56:37 INFO - Initialising browser for dromaeo_css test... 09:56:37 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpjnWbLY/profile http://localhost:49256/getInfo.html) 09:56:39 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 09:56:39 INFO - PROCESS | 1640 | colorDepth:24 09:56:39 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 09:56:39 INFO - PROCESS | 1640 | __metrics 09:56:39 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3015: TypeError: this.tabs is undefined 09:56:40 INFO - TEST-INFO | 1640: exit 0 09:56:40 INFO - Browser initialized. 09:56:40 INFO - Running cycle 1/1 for dromaeo_css test... 09:56:40 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpjnWbLY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:56:52 INFO - PROCESS | 1644 | RSS: Main: 174833664 09:56:52 INFO - PROCESS | 1644 | 09:57:42 INFO - PROCESS | 1644 | 1484243862218 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:59:53 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49256/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49256/tests/dromaeo/cssquery-ext.html) 09:59:54 INFO - PROCESS | 1644 | RSS: Main: 186142720 09:59:54 INFO - PROCESS | 1644 | 10:02:56 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49256/tests/dromaeo/cssquery-ext.html (next: http://localhost:49256/tests/dromaeo/cssquery-jquery.html) 10:02:57 INFO - PROCESS | 1644 | RSS: Main: 196395008 10:02:57 INFO - PROCESS | 1644 | 10:05:59 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49256/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49256/tests/dromaeo/cssquery-mootools.html) 10:06:00 INFO - PROCESS | 1644 | RSS: Main: 201674752 10:06:00 INFO - PROCESS | 1644 | 10:09:01 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49256/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49256/tests/dromaeo/cssquery-prototype.html) 10:09:02 INFO - PROCESS | 1644 | RSS: Main: 205987840 10:09:02 INFO - PROCESS | 1644 | 10:12:05 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49256/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49256/tests/dromaeo/cssquery-yui.html) 10:12:05 INFO - PROCESS | 1644 | RSS: Main: 208363520 10:12:05 INFO - PROCESS | 1644 | 10:15:08 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49256/tests/dromaeo/cssquery-yui.html (next: http://localhost:49256/tests/dromaeo/cssquery-dojo.html) 10:15:08 INFO - PROCESS | 1644 | RSS: Main: 209108992 10:15:08 INFO - PROCESS | 1644 | 10:15:08 INFO - PROCESS | 1644 | __start_tp_report 10:15:08 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 10:15:08 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 10:15:08 INFO - PROCESS | 1644 | |i|pagename|runs| 10:15:08 INFO - PROCESS | 1644 | |0;dojo.html;2991.9252018699535;3617.113807117257;3623.92752144957;3649;3679.0250583595353;5910.290765108182;5956.166136740854;5976.52187824974;6004.189434426351;6005.3394126646035;5956.940430595692;5980.8504787380225;6045.183900173475;6051;6064.818055458343;5394.298741163643;5397.001554712371;5406.756695948692;5469.644473109255;5557.360903496089;5446.509814116729;5464.23500709901;5484.862878428051;5488.231647569343;5504.559635229172;5340.732963351837;5364.973175134119;5457.863553411157;5464.890702185954;5465.9180112298345;4376.606105450493;5383.327084114505;5407;5421.2952316199035;5446.782128714846;4497.640188784922;5320.095583750733;5355.437679043722;5360.571154307646;5432.91850622241;6041.879162416771;6089.969550152266;6121.265448146207;6142.8771424571705;6146.84632884177;5150.665206761549;6198.62808231503;6212.440880320748;6226.87546249077;6263.123162757218;6316.08416779569;6319.778807741707;6328.430441260262;6374.298827129012;6386.776462823779;5721.284839395075;6084.087386891957;6096.634201947897;6099.207103076616;6104.786332478342;6350.6189628621505;6357.332480089524;6410.711517981702;6416.262129855127;6441.64570948593;6297.811065668037;6310.305866354697;6321.051842223703;6328;6333.651649159248;6211.13044173807;6275.780347687901;6378.872422551523;6385.680715964183;6395.104685344056;6215.844603884939;6246.906296405557;6251.874962500816;6315.336889626523;6325.051242313598;6270.592411493239;6277.372262773686;6306.369363063749;6315.147455093595;6324.272708638474;4277;4348.804078878334;5008.373953255843;5059.620528460307;5094.439611642716;4463.8214471421425;4544.159330523864;4568;4587.059652771174;4592.7933243004145;6072.1498990140535;6088.360722124202;6140.232470941132;6172.722227499773;6197.101420294032;6398.488120950313;6405.135306733531;6406.615603063831;6407.839804004844;6422.614643121427;6016.789412370546;6066.150738896558;6109.070667813081;6109.450149486567;6142.815715528542;5617.185508101303;5619;5641.858953526113;5648.830535083954;5682.289713785777;6496.187976502937;6519.543631945813;6561.179852518435;6569.57297775643;6580.967095164494;6192.690365481618;6193.907091393543;6266.905996410149;6272.059191121369;6347.90478142819;6525.347465253437;6544.149260596282;6550.443212326815;6551.148350714377;6562.50781191403;5317.6543524672325;6300.527460440394;6312.3056463788025;6331;6334.239891213084;5554.527865131509;5813.505852002458;6264.59280146789;6305.306416294112;6365.586236894587;5129.589632829439;6250.874982500415;6277;6295.811125666238;6307.148534947907;6293.056041593798;6298.937010630018;6303.653299068412;6321.620702757849;6322.399372059647;5970.58205925581;6134.681512609229;6204.410580994799;6252.09344645033;6287.622742635456;6215.533834962305;6240.188775459162;6255.31191568918;6293.622382657055;6317.873642527215;6240.906386404298;6285.2457705075685;6296.401841825203;6297.559170857996;6319.083732858801 10:15:08 INFO - PROCESS | 1644 | |1;ext.html;504.08508556969076;523.6046784677568;535.3682654467726;538.477676653646;544.4093158922572;40432;42422;42453.57546424535;42475;42521;44356;44651.55348446523;44800.77599611998;44913.55086449134;44957.55042449575;9947.602095916154;10036.046575575308;10047.296689231756;10088.243381746363;10102;9902.801943961116;10006;10017.248706347016;10020.19838412929;10041.497925103715;10013.299069065168;10019;10033.498325083752;10037.498125093716;10058.245631577623;9977.75055623608;10038.598456061749;10041.19670426364;10046.095851373411;10076.69769906904;8601.268892144175;10053.497325133714;10058.698239052841;10067.69796906094;10087.394756314645;42512.78743606262;42521.93695157615;42550.36174457386;43079;43081;2910.286979689968;3552.6802587767224;3627.093226693327;3884.242572698325;4382.868513944587;31479.055628330956;31515.84242078798;31992.680073199204;31994;32003.359932801213;3355.39602871483;3381.847816848248;3441.346144232613;3442.5868895732424;3443.517907492953;520046;526267;526699;527834.3608281972;529712;31837;52198.73900630473;57777.711111445016;59563;59819;33732.98801035973;35227;35278.35412344033;36061.196940152884;36186.638133618326;15724.764128538081;15931.884768066124;15952.76070858938;15974.76037859433;15980.520584382488;13134.211947283193;13391.598252052454;13449.125806822722;13474.393652285671;13516.324183790772;6923.446124310144;7837.177096404909;8050.27547520726;8063;8083.434159608771;3411.965880341165;3428.5885693716914;3440.690337869604;3441.0881116504147;3462.774919630216;897.9281657467387;980.7352014955922;1057.8889216632297;1102.972425689348;1187.6496433552122;25489.107881224067;31809;31953.68046319507;32264.83867580694;32324.83837580797;1060.8860696268887;1078.7217147680024;1081.291753901196;1136.6078702847503;1176.253079294654;15546.37814487408;15882.76175857363;16083.35666573324;16179.757303640456;16411.343546258016;595448;698901;709408;711134;717252.827471729;57252.713736431055;58569;58756.41243587595;59134.40865591289;59152;1381.3645722967547;1393.6515871032243;1415.950441734555;1435.6195608163846;1436.2100844535464;29962.850185748932;30279.788808447414;31917;32241.51637725389;32353;57840.843183136945;58640.70679646575;58699.4130058711;58845.70577147087;58868.11697824451;657263;659892;661142;665226.6738666276;665657;44656;44675.55324446714;44702.3294650587;44955.32567011432;45020.77489612531;47728.045439091715;48932.40813657976;49582.504174959235;50004.24993625171;50196.49803501918;40881;40918;41361;41468;42902;41819.581804181566;42021.579784201764;42290.788546057076;42554.361684574134;42595.14809703851;39460.60539394684;40493.79753101333;40703;40956.38565421462;40962;34702.47946280858;36035.459468107474;36231.429012853456;36429.783654922205;36781.71264005747;26917.461650766483;26940;26971.190864274104;27042.459150817267;27111.72888271092 10:15:08 INFO - PROCESS | 1644 | |2;jquery.html;386.0434961985028;387.1154412168196;387.1753165756937;394.29421335808894;414.5419311660615;20314.984250787456;20536.691949620767;21289.893550532226;21467.892660536694;21481.248156314534;20689.793102069012;20770.169193232254;20970.37088887336;21302.46743831405;21811.127554897786;5250.527452529272;5557.166425036236;5566.359868615121;5607.214989901417;5619.719014049301;5414.702191379472;5551.139573366135;5568.470995255465;5599.7480113394995;5847.298324201101;5996;6067.211262535864;6089.756409743627;6117.2659280886355;6162.014077747562;5443.945560544384;5502.80740174092;5662.631928924648;6113.694315284218;6116.235470566179;4746.501617330166;4896.363472748555;5125.821061155955;5136.794528218867;5317.973410132963;13502.864971350356;19059.90470047655;25082.372940676447;25707.61438578423;26420.73579264202;2218.9556208875733;2252.583272094668;2259.378670865509;2324.1052194905083;2355.7526459721653;10200.285979981405;10296.948515257376;10405.011523905292;10624.946875265574;10684.839727404094;1940.4469726127986;1993.2027189124321;2029.350607805503;2038.9184432622826;2078.511549785799;120130;121448;124574.37712811469;131550;132116;10026.298159128792;12006.759864802654;12012.459439325252;12218.572349967708;12362.01103911703;14164.22096784666;14737.705245895022;14741.778873316909;15050.924745376422;15063.924680376527;8913;8987.820243595092;9045.457272563668;9053.502057386775;9151.862722059175;7553.509021913558;9016.684416045407;9055.139761722627;9060.682876099305;9165.083491650781;4622.815087396534;4658.836940707127;4671.346011558385;4679.976600116977;4746.216874215785;1946.8734532255357;1986.9205231790856;1996.452749119444;2004.959900801976;2008.347287131688;801.294860522748;864.4553931023415;930.6696122876417;931.9161275485103;952.5332587032304;17828.821711782977;20274.89862550678;20444.386668399973;21534.56930861374;21594.136234550755;923.215267023025;939.7603611079131;941.7410212191702;944.6788092048737;981.2444417798256;11018.1185505161;11339.206255562196;11344.262622929484;11365.772684546262;11372.943135284271;585892;602372;632950;637187;641247;120378.3981080089;135336;139090;146704;149052;423.9046214601752;441.6510956344452;446.475391415086;448.2514201283929;448.62988034871364;10951.890481095086;11419.486123124645;11636.592719254786;11669.76660466803;11743.17797754149;53948.73025634847;57208.141877871036;58005;58932;59901.700491497264;74374.62812686118;76322.6183869077;76864.61567692125;80255;80707.19292806997;8196.262336389753;8326.29226515753;8339.332853371838;8349.83300334002;8382.538960357238;12633.620991370273;13156.897926634616;13268.668283292609;13358.198508089547;13442.865571344162;11456;11488.253263537843;11521.366324852213;11651.359175245443;11678.474468649101;8843.955780221058;11015.944920275348;11071.723206919569;11165.330080195214;11279.661610151708;9599.952000239955;9642.566084526074;10663.613730215022;11210.775784484427;11479.942600286946;5851.64890106595;8231.629576668944;8445.577721114043;8487.872681909652;8697.565121744014;9525.995409639701;9687.41875487473;10090.54592543352;11395.886041139482;11615.709607259549 10:15:08 INFO - PROCESS | 1644 | |3;mootools.html;1427.7786943023827;1466.4280930437128;1778.2531336838526;1917.2331067572977;1978.7823339432668;8301.792455188608;8545.786355341119;8789.472631642106;9328.30037747169;9339.673111441092;8763.298936085113;9029.593668284926;9038.54807259636;9117.997020327775;9125.361224714272;2420.745821688728;2428.86641234732;2429.307647320514;2448.51029794041;2477.6531285620026;2448.6327050942323;2455.1407007547305;2480.677511923456;2487.7636624520733;2490.816861990549;2455.4107014316523;2468.7654672806143;2474.64117702933;2481.0944005438064;2481.6153496208117;2404.2426635609727;2432.756724327562;2434.476587533671;2443.2303824295377;2459.655648209234;2397.724261709908;2425.866577338246;2446.0460420436043;2458.3485376375274;2462.4090218347646;9163.633454661805;9183.90816091844;9199.58601862911;9357.251419886392;9405.76485587859;5459.890802183934;5550.611457197998;5615.2700148980375;5637.689927054011;5664.433556644344;3297.1262615406936;3318.3363327334387;3712.9443058354145;3756.229972855558;3764.7176461765202;1077.8329358949375;1111.188832152525;1118.3178261260625;1138.3397629374942;1139.9316041037482;11996.340201288926;12036;12041.397930103547;12099.879001209963;12116.575919842764;4811.206150985118;4873.269009648581;4900.485449027872;4989.027139707722;4996.125678006334;10266.691999240034;10297.02178293061;10353.119984801377;10360.637377691744;10361.963803619728;5498.285222920995;5503.6147469677535;5568.3318001839225;5579;5627.015272327327;4605.240135377692;4718.551737584924;4920.72935988524;5031.899362012739;5050.116229659869;4064.7967601619803;4069.1861627674584;4124.52567954689;4165.500139983161;4192.685548583869;1164.0861923390098;1328.9667758305927;1332.1141440941665;1339.5378594384922;1345.5156143788229;928.2898582584328;928.9767755806159;942.5569982108398;943;965.2567523007245;5831.825045248649;5920.437558432028;5932.228810254639;6066.605670631395;6150.477209437248;447.39601537923545;447.61057879644915;453.26797222486107;467.6562726396096;477.11257061864904;3697.8721489945287;3812.542494900574;4055.4727885374714;4117.382392641128;4119.958800412018;10550.366977981346;10839.295445795999;10978.505967231495;10997.61508347204;11095.83356249657;9330.253579713606;10701.83947240792;10836.241463097665;11039.72400689973;11079.113670906308;8337.082920878698;8508.191721786545;8508.787280318042;8602.827943441096;8617.267532259702;5876.471117599436;5995.130706047686;6048.818535443944;6060.272767267956;6145.170401995673;8598.39811213209;9995.450250236307;10098.54556544972;10432.165426765689;10525.947370263406;10604.310719803188;10623.521941512496;10654.8401773975;10667.57329706834;10716.089132423831;7055.435565154878;7253.3834624057545;7519.1728909818785;7732.536047836923;7781.805454863674;8477.830443390974;8608.526531040852;8611.526366049928;8752;8775;7671.462997590115;7777.766666999999;7821.335186508983;7856.882146767917;7943.4042446817875;7575.659095340613;7689.461737678309;7702.730404435818;7717.652705628372;7724.7682569523;7159.928400715926;7291.34377905991;7296.379807716404;7301.123865136217;7306.817329566591;4962.379702537183;5027.824026159067;5077.416097148875;5134.640575159703;5272.657277276965;5037.748112594429;5129.8974020518635;5149.150390185728;5257;5291.021161085211 10:15:08 INFO - PROCESS | 1644 | |4;prototype.html;280.7711714952315;312.3035630543887;313.4405086919848;316.18109097437633;318.00306040562845;5440.292761940942;5469.042917489438;5514.95215908977;5575.041720378526;5586.217929489874;4433.379326894237;4528.569785870345;4663.766811659412;5396.138332275368;5413.296271484702;3953.505811773521;4079.816853112594;4090.3046482098116;4127.855525056623;4167.499900012002;3689.262147570483;4079.4900637420324;4117.794110294488;4134.834606615732;4184.8535301264465;3432.4508078707413;4058.8579399721016;4149.502059752834;4182.2681030819485;4195.4545909031785;4029.738067025634;4090.380013687878;4167.2487775671;4175.290200665894;4209.810558524843;3151;3584.6953008994265;3606.6934310583633;3608.314420260142;3623.3296840084668;4552.361149986003;4646.883827904296;5257.553107985826;5291.232771248147;5379.919301210485;4631.907361852744;4806.567408933178;4809.663323567352;4818.638602104821;4837.782299796518;4973.875653108665;5306.310179676618;5702.800401985952;5716.114002329646;5828;3951.308521008812;3984.8406063757416;3992.602589093823;4014.879553613367;4026.6577340926056;4848.612111031109;5828.533717302648;5867.237259156283;6011.549133814981;6041.969790151021;3312.850921708529;3444.776089554171;4046.3728122141115;4057.431959525669;4088.7137900347284;3188.601424821897;3858.8070596470066;4122.330456165258;4167.83328666856;4176.2691528982305;4535.435274830178;4790.7365094920115;4815.0868724421925;4837.7097374157665;4848.903021939542;4802.927956080662;5320.787168513216;5800.796972105956;5861.9706901465215;5898.8230353089475;1142.5429828068839;1293.482606957206;1388.9097058808786;1401.6355747505722;1401.950931717385;851.7529916324197;877.394597727566;887.8046829697456;908.6229214875752;933.6545478172984;4563.361129441881;5173.301604283449;5273.209018647157;5450.345958485007;5471.206675032098;5964.821055368259;5973.402659733992;6020.608660437058;6021.909671354933;6059.6364218147055;4929.630277729185;4986.401631804207;5076.593872490192;5304.965531721277;5542.418046105182;2824.331007198178;3091.335362896988;3100;3117.579126817896;3158.8104713717253;5891.263592050994;6113.187933550616;6130.908036379458;6162.425793040026;6178.382161783876;4728.456227533878;5437.32033495813;5753.4821866031425;6185.536084793658;6255.78104766331;5151.639385242997;5202.9677000325055;5824.0303688719405;6176.66028368444;6266.749330026839;5511;5514.283143191359;5883.235179426647;6119.571629985859;6144.754209831735;5103.08531939217;5375.00562395958;5954.1244507979845;5963.793396443344;5983.34183239852;5437.537809286255;5682.573806964577;5937;5981.282246130492;6306.590071645328;3753.268112717994;3973.2252210818615;4001.8999525010945;4012.678985681197;4021.256067627381;4989.476105008922;5963.850903727442;5981.7906373278465;6018.3981601841215;6029.306629737636;4821.831235906727;5140.56305214061;5831.658718494685;5855.882882342415;5856.472917437451;5289.973550132225;5428.131498960148;5819.272590926134;5874.236349274567;5952.255968003999;5735.082386818089;5751.504608801658;5780.046292361713;5793.420657934342;5802.622829516068;5133.768980395966;5351.518363347317;5581.302337207849;5677.659340439587;5814.273215848019;5702;5711.571632127524;5743.253377061123;5758.568107391879;5784.021229067118 10:15:08 INFO - PROCESS | 1644 | |5;yui.html;687.8761822871883;736.7458226911717;763.3435245688706;768.861604911116;859.8280343931216;15670.764938525917;15963.122028288439;16111;16607.418740344092;16664.583385415357;14815;14991.92504037481;15000.324985375657;15003.324850381732;15019.32413041413;710.1300906389657;735.7939776862487;871.0897112517434;874.8950125984875;887.8978917424514;728.5810658871144;833.8659423184464;866.2030931542996;870.060334838373;872.0668884293776;911.5214512380986;931.7297983584787;932.1703683721494;933.169479163545;933.6685476655791;784.7175016993878;905.5743800413795;927.0544045074054;928.9024652411467;935.5649622925339;607.3683369295927;653.8332725155102;683.1597135523334;689.7034275261635;691.3466773898667;14843.480478183319;14917.477888273967;14919.701605967819;14924.029938053991;14938.925305373403;14980.850191498055;14998.400063997427;15059.774103388458;15144.848551514455;15213.543593692208;11895.405229738479;11899.405029748477;11945.109780439121;12059.155859089868;12492;3989.40158976153;4028.3151864182864;4048.817803198863;4071.857484988011;4129.504459464883;115611;120709;121851.3907430466;122750.77249227393;124033;10959.6797425225;11325.603603873826;11391.544338226378;11745.588904388476;11783.116266280063;23653.7634623655;23669.171578994654;23912.40218994539;24026.279211623678;24030.038798447866;7738.61306934651;7893.763187104396;8346.582670866432;8504.404691671647;8536.823110107007;4417.668674849335;4608.953910460919;4648.907021859543;4656.32483289921;4697.553732395417;3489.616142224353;3562.2163124112653;3624.6919011883756;3626.437902125175;3934.15415685629;736.8231624410103;741.3550211316082;743.0934260202545;748.7566540874129;766.8082979255187;625.9029850373199;646.7380710812129;677.6577828196729;682.952193346471;686.9072675188886;9339.486328251876;9530.332876698565;9720.75698107553;9768.95115524432;9827.950860245795;634.6636282770139;670.9194896612438;673.4006734006726;701.8140192848899;713.8715031294313;4386.473623165177;4526.162659907927;4754.263089221176;4838.693661936884;4856.514348565186;151431.2428437851;152228;152721;152776;155496;79274;83614.58192708998;118068.40965795115;124223;135338;113783;116993;117972.23041654383;118694.21958670627;119258.40370798264;8985.640574377214;9163.86254206174;9168.266538677024;9223.907760922304;9318.95340523293;57256;58353.4164658348;58964.41035589589;59277.407225927185;60364.396356035875;84061.57969210115;86968.56515717381;88368;89456;90992.5450372744;11876.168668193144;12002.879971200176;12087.274763514217;12148.757024859276;12350.876491235333;12682;12866.134410510236;13249;13541.661458463617;13672;12791;12795.36023198855;12817.23096614206;12910.870891290966;13142.34288285601;12641;12724.872751272738;12788.744225115632;13096.083274170716;13162.539311124065;12514.31171285588;12518.311492867615;12618.9904807617;12631.93684031574;12745.426455809325;8702.434341767765;10817.918208179119;10842.403667798031;11007.834882476609;11372.033377162703;11020.559177633126;11037.724056898642;11209.215354925076;11254.943725281648;11286.081366167935 10:15:08 INFO - PROCESS | 1644 | __end_tp_report 10:15:08 INFO - PROCESS | 1644 | __start_cc_report 10:15:08 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,358 10:15:08 INFO - PROCESS | 1644 | __end_cc_report 10:15:08 INFO - PROCESS | 1644 | __startTimestamp1484244908602__endTimestamp 10:15:08 INFO - PROCESS | 1644 | 10:15:08 INFO - PROCESS | 1644 | ------- Summary: start ------- 10:15:08 INFO - PROCESS | 1644 | Number of tests: 6 10:15:08 INFO - PROCESS | 1644 | 10:15:08 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5894.93 Median:6193.91 stddev:672.51 (10.9%) stddev-sans-first:634.84 10:15:08 INFO - PROCESS | 1644 | Values: 2991.9 3617.1 3623.9 3649.0 3679.0 5910.3 5956.2 5976.5 6004.2 6005.3 5956.9 5980.9 6045.2 6051.0 6064.8 5394.3 5397.0 5406.8 5469.6 5557.4 5446.5 5464.2 5484.9 5488.2 5504.6 5340.7 5365.0 5457.9 5464.9 5465.9 4376.6 5383.3 5407.0 5421.3 5446.8 4497.6 5320.1 5355.4 5360.6 5432.9 6041.9 6090.0 6121.3 6142.9 6146.8 5150.7 6198.6 6212.4 6226.9 6263.1 6316.1 6319.8 6328.4 6374.3 6386.8 5721.3 6084.1 6096.6 6099.2 6104.8 6350.6 6357.3 6410.7 6416.3 6441.6 6297.8 6310.3 6321.1 6328.0 6333.7 6211.1 6275.8 6378.9 6385.7 6395.1 6215.8 6246.9 6251.9 6315.3 6325.1 6270.6 6277.4 6306.4 6315.1 6324.3 4277.0 4348.8 5008.4 5059.6 5094.4 4463.8 4544.2 4568.0 4587.1 4592.8 6072.1 6088.4 6140.2 6172.7 6197.1 6398.5 6405.1 6406.6 6407.8 6422.6 6016.8 6066.2 6109.1 6109.5 6142.8 5617.2 5619.0 5641.9 5648.8 5682.3 6496.2 6519.5 6561.2 6569.6 6581.0 6192.7 6193.9 6266.9 6272.1 6347.9 6525.3 6544.1 6550.4 6551.1 6562.5 5317.7 6300.5 6312.3 6331.0 6334.2 5554.5 5813.5 6264.6 6305.3 6365.6 5129.6 6250.9 6277.0 6295.8 6307.1 6293.1 6298.9 6303.7 6321.6 6322.4 5970.6 6134.7 6204.4 6252.1 6287.6 6215.5 6240.2 6255.3 6293.6 6317.9 6240.9 6285.2 6296.4 6297.6 6319.1 10:15:08 INFO - PROCESS | 1644 | 10:15:08 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:75042.91 Median:20028.44 stddev:168612.65 (841.9%) stddev-sans-first:168992.78 10:15:08 INFO - PROCESS | 1644 | Values: 504.1 523.6 535.4 538.5 544.4 40432.0 42422.0 42453.6 42475.0 42521.0 44356.0 44651.6 44800.8 44913.6 44957.6 9947.6 10036.0 10047.3 10088.2 10102.0 9902.8 10006.0 10017.2 10020.2 10041.5 10013.3 10019.0 10033.5 10037.5 10058.2 9977.8 10038.6 10041.2 10046.1 10076.7 8601.3 10053.5 10058.7 10067.7 10087.4 42512.8 42521.9 42550.4 43079.0 43081.0 2910.3 3552.7 3627.1 3884.2 4382.9 31479.1 31515.8 31992.7 31994.0 32003.4 3355.4 3381.8 3441.3 3442.6 3443.5 520046.0 526267.0 526699.0 527834.4 529712.0 31837.0 52198.7 57777.7 59563.0 59819.0 33733.0 35227.0 35278.4 36061.2 36186.6 15724.8 15931.9 15952.8 15974.8 15980.5 13134.2 13391.6 13449.1 13474.4 13516.3 6923.4 7837.2 8050.3 8063.0 8083.4 3412.0 3428.6 3440.7 3441.1 3462.8 897.9 980.7 1057.9 1103.0 1187.6 25489.1 31809.0 31953.7 32264.8 32324.8 1060.9 1078.7 1081.3 1136.6 1176.3 15546.4 15882.8 16083.4 16179.8 16411.3 595448.0 698901.0 709408.0 711134.0 717252.8 57252.7 58569.0 58756.4 59134.4 59152.0 1381.4 1393.7 1416.0 1435.6 1436.2 29962.9 30279.8 31917.0 32241.5 32353.0 57840.8 58640.7 58699.4 58845.7 58868.1 657263.0 659892.0 661142.0 665226.7 665657.0 44656.0 44675.6 44702.3 44955.3 45020.8 47728.0 48932.4 49582.5 50004.2 50196.5 40881.0 40918.0 41361.0 41468.0 42902.0 41819.6 42021.6 42290.8 42554.4 42595.1 39460.6 40493.8 40703.0 40956.4 40962.0 34702.5 36035.5 36231.4 36429.8 36781.7 26917.5 26940.0 26971.2 27042.5 27111.7 10:15:08 INFO - PROCESS | 1644 | 10:15:08 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:35986.81 Median:386.58 stddev:103873.24 (26869.8%) stddev-sans-first:104130.23 10:15:08 INFO - PROCESS | 1644 | Values: 386.0 387.1 387.2 394.3 414.5 20315.0 20536.7 21289.9 21467.9 21481.2 20689.8 20770.2 20970.4 21302.5 21811.1 5250.5 5557.2 5566.4 5607.2 5619.7 5414.7 5551.1 5568.5 5599.7 5847.3 5996.0 6067.2 6089.8 6117.3 6162.0 5443.9 5502.8 5662.6 6113.7 6116.2 4746.5 4896.4 5125.8 5136.8 5318.0 13502.9 19059.9 25082.4 25707.6 26420.7 2219.0 2252.6 2259.4 2324.1 2355.8 10200.3 10296.9 10405.0 10624.9 10684.8 1940.4 1993.2 2029.4 2038.9 2078.5 120130.0 121448.0 124574.4 131550.0 132116.0 10026.3 12006.8 12012.5 12218.6 12362.0 14164.2 14737.7 14741.8 15050.9 15063.9 8913.0 8987.8 9045.5 9053.5 9151.9 7553.5 9016.7 9055.1 9060.7 9165.1 4622.8 4658.8 4671.3 4680.0 4746.2 1946.9 1986.9 1996.5 2005.0 2008.3 801.3 864.5 930.7 931.9 952.5 17828.8 20274.9 20444.4 21534.6 21594.1 923.2 939.8 941.7 944.7 981.2 11018.1 11339.2 11344.3 11365.8 11372.9 585892.0 602372.0 632950.0 637187.0 641247.0 120378.4 135336.0 139090.0 146704.0 149052.0 423.9 441.7 446.5 448.3 448.6 10951.9 11419.5 11636.6 11669.8 11743.2 53948.7 57208.1 58005.0 58932.0 59901.7 74374.6 76322.6 76864.6 80255.0 80707.2 8196.3 8326.3 8339.3 8349.8 8382.5 12633.6 13156.9 13268.7 13358.2 13442.9 11456.0 11488.3 11521.4 11651.4 11678.5 8844.0 11015.9 11071.7 11165.3 11279.7 9600.0 9642.6 10663.6 11210.8 11479.9 5851.6 8231.6 8445.6 8487.9 8697.6 9526.0 9687.4 10090.5 11395.9 11615.7 10:15:08 INFO - PROCESS | 1644 | 10:15:08 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5878.67 Median:4886.88 stddev:3305.39 (67.6%) stddev-sans-first:3297.73 10:15:08 INFO - PROCESS | 1644 | Values: 1427.8 1466.4 1778.3 1917.2 1978.8 8301.8 8545.8 8789.5 9328.3 9339.7 8763.3 9029.6 9038.5 9118.0 9125.4 2420.7 2428.9 2429.3 2448.5 2477.7 2448.6 2455.1 2480.7 2487.8 2490.8 2455.4 2468.8 2474.6 2481.1 2481.6 2404.2 2432.8 2434.5 2443.2 2459.7 2397.7 2425.9 2446.0 2458.3 2462.4 9163.6 9183.9 9199.6 9357.3 9405.8 5459.9 5550.6 5615.3 5637.7 5664.4 3297.1 3318.3 3712.9 3756.2 3764.7 1077.8 1111.2 1118.3 1138.3 1139.9 11996.3 12036.0 12041.4 12099.9 12116.6 4811.2 4873.3 4900.5 4989.0 4996.1 10266.7 10297.0 10353.1 10360.6 10362.0 5498.3 5503.6 5568.3 5579.0 5627.0 4605.2 4718.6 4920.7 5031.9 5050.1 4064.8 4069.2 4124.5 4165.5 4192.7 1164.1 1329.0 1332.1 1339.5 1345.5 928.3 929.0 942.6 943.0 965.3 5831.8 5920.4 5932.2 6066.6 6150.5 447.4 447.6 453.3 467.7 477.1 3697.9 3812.5 4055.5 4117.4 4120.0 10550.4 10839.3 10978.5 10997.6 11095.8 9330.3 10701.8 10836.2 11039.7 11079.1 8337.1 8508.2 8508.8 8602.8 8617.3 5876.5 5995.1 6048.8 6060.3 6145.2 8598.4 9995.5 10098.5 10432.2 10525.9 10604.3 10623.5 10654.8 10667.6 10716.1 7055.4 7253.4 7519.2 7732.5 7781.8 8477.8 8608.5 8611.5 8752.0 8775.0 7671.5 7777.8 7821.3 7856.9 7943.4 7575.7 7689.5 7702.7 7717.7 7724.8 7159.9 7291.3 7296.4 7301.1 7306.8 4962.4 5027.8 5077.4 5134.6 5272.7 5037.7 5129.9 5149.2 5257.0 5291.0 10:15:08 INFO - PROCESS | 1644 | 10:15:08 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4665.78 Median:5230.26 stddev:1444.70 (27.6%) stddev-sans-first:1410.77 10:15:08 INFO - PROCESS | 1644 | Values: 280.8 312.3 313.4 316.2 318.0 5440.3 5469.0 5515.0 5575.0 5586.2 4433.4 4528.6 4663.8 5396.1 5413.3 3953.5 4079.8 4090.3 4127.9 4167.5 3689.3 4079.5 4117.8 4134.8 4184.9 3432.5 4058.9 4149.5 4182.3 4195.5 4029.7 4090.4 4167.2 4175.3 4209.8 3151.0 3584.7 3606.7 3608.3 3623.3 4552.4 4646.9 5257.6 5291.2 5379.9 4631.9 4806.6 4809.7 4818.6 4837.8 4973.9 5306.3 5702.8 5716.1 5828.0 3951.3 3984.8 3992.6 4014.9 4026.7 4848.6 5828.5 5867.2 6011.5 6042.0 3312.9 3444.8 4046.4 4057.4 4088.7 3188.6 3858.8 4122.3 4167.8 4176.3 4535.4 4790.7 4815.1 4837.7 4848.9 4802.9 5320.8 5800.8 5862.0 5898.8 1142.5 1293.5 1388.9 1401.6 1402.0 851.8 877.4 887.8 908.6 933.7 4563.4 5173.3 5273.2 5450.3 5471.2 5964.8 5973.4 6020.6 6021.9 6059.6 4929.6 4986.4 5076.6 5305.0 5542.4 2824.3 3091.3 3100.0 3117.6 3158.8 5891.3 6113.2 6130.9 6162.4 6178.4 4728.5 5437.3 5753.5 6185.5 6255.8 5151.6 5203.0 5824.0 6176.7 6266.7 5511.0 5514.3 5883.2 6119.6 6144.8 5103.1 5375.0 5954.1 5963.8 5983.3 5437.5 5682.6 5937.0 5981.3 6306.6 3753.3 3973.2 4001.9 4012.7 4021.3 4989.5 5963.9 5981.8 6018.4 6029.3 4821.8 5140.6 5831.7 5855.9 5856.5 5290.0 5428.1 5819.3 5874.2 5952.3 5735.1 5751.5 5780.0 5793.4 5802.6 5133.8 5351.5 5581.3 5677.7 5814.3 5702.0 5711.6 5743.3 5758.6 5784.0 10:15:08 INFO - PROCESS | 1644 | 10:15:08 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:24751.80 Median:3780.30 stddev:39659.08 (1049.1%) stddev-sans-first:39729.18 10:15:08 INFO - PROCESS | 1644 | Values: 687.9 736.7 763.3 768.9 859.8 15670.8 15963.1 16111.0 16607.4 16664.6 14815.0 14991.9 15000.3 15003.3 15019.3 710.1 735.8 871.1 874.9 887.9 728.6 833.9 866.2 870.1 872.1 911.5 931.7 932.2 933.2 933.7 784.7 905.6 927.1 928.9 935.6 607.4 653.8 683.2 689.7 691.3 14843.5 14917.5 14919.7 14924.0 14938.9 14980.9 14998.4 15059.8 15144.8 15213.5 11895.4 11899.4 11945.1 12059.2 12492.0 3989.4 4028.3 4048.8 4071.9 4129.5 115611.0 120709.0 121851.4 122750.8 124033.0 10959.7 11325.6 11391.5 11745.6 11783.1 23653.8 23669.2 23912.4 24026.3 24030.0 7738.6 7893.8 8346.6 8504.4 8536.8 4417.7 4609.0 4648.9 4656.3 4697.6 3489.6 3562.2 3624.7 3626.4 3934.2 736.8 741.4 743.1 748.8 766.8 625.9 646.7 677.7 683.0 686.9 9339.5 9530.3 9720.8 9769.0 9828.0 634.7 670.9 673.4 701.8 713.9 4386.5 4526.2 4754.3 4838.7 4856.5 151431.2 152228.0 152721.0 152776.0 155496.0 79274.0 83614.6 118068.4 124223.0 135338.0 113783.0 116993.0 117972.2 118694.2 119258.4 8985.6 9163.9 9168.3 9223.9 9319.0 57256.0 58353.4 58964.4 59277.4 60364.4 84061.6 86968.6 88368.0 89456.0 90992.5 11876.2 12002.9 12087.3 12148.8 12350.9 12682.0 12866.1 13249.0 13541.7 13672.0 12791.0 12795.4 12817.2 12910.9 13142.3 12641.0 12724.9 12788.7 13096.1 13162.5 12514.3 12518.3 12619.0 12631.9 12745.4 8702.4 10817.9 10842.4 11007.8 11372.0 11020.6 11037.7 11209.2 11254.9 11286.1 10:15:08 INFO - PROCESS | 1644 | -------- Summary: end -------- 10:15:08 INFO - PROCESS | 1644 | 10:15:09 INFO - TEST-INFO | 1644: exit 0 10:15:09 INFO - TEST-OK | dromaeo_css | took 1112038ms 10:15:09 INFO - TEST-START | kraken 10:15:09 INFO - operating with platform_type : mac_ 10:15:09 INFO - Initialising browser for kraken test... 10:15:09 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpsAWanF/profile http://localhost:49256/getInfo.html) 10:15:11 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 10:15:11 INFO - PROCESS | 1655 | colorDepth:24 10:15:11 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 10:15:11 INFO - PROCESS | 1655 | __metrics 10:15:11 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3015: TypeError: this.tabs is undefined 10:15:12 INFO - TEST-INFO | 1655: exit 0 10:15:12 INFO - Browser initialized. 10:15:12 INFO - Running cycle 1/1 for kraken test... 10:15:12 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpsAWanF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:15:24 INFO - PROCESS | 1658 | RSS: Main: 174964736 10:15:24 INFO - PROCESS | 1658 | 10:15:26 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49256/tests/kraken/driver.html?testName=audio-beat-detection) 10:15:27 INFO - PROCESS | 1658 | RSS: Main: 187142144 10:15:27 INFO - PROCESS | 1658 | 10:15:30 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49256/tests/kraken/driver.html?testName=audio-dft) 10:15:30 INFO - PROCESS | 1658 | RSS: Main: 195776512 10:15:30 INFO - PROCESS | 1658 | 10:15:34 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49256/tests/kraken/driver.html?testName=audio-fft) 10:15:35 INFO - PROCESS | 1658 | RSS: Main: 198606848 10:15:35 INFO - PROCESS | 1658 | 10:15:36 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49256/tests/kraken/driver.html?testName=audio-oscillator) 10:15:37 INFO - PROCESS | 1658 | RSS: Main: 200044544 10:15:37 INFO - PROCESS | 1658 | 10:15:39 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49256/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:15:40 INFO - PROCESS | 1658 | RSS: Main: 199962624 10:15:40 INFO - PROCESS | 1658 | 10:15:43 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49256/tests/kraken/driver.html?testName=imaging-darkroom) 10:15:43 INFO - PROCESS | 1658 | RSS: Main: 201027584 10:15:43 INFO - PROCESS | 1658 | 10:15:46 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49256/tests/kraken/driver.html?testName=imaging-desaturate) 10:15:47 INFO - PROCESS | 1658 | RSS: Main: 200413184 10:15:47 INFO - PROCESS | 1658 | 10:15:50 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49256/tests/kraken/driver.html?testName=json-parse-financial) 10:15:50 INFO - PROCESS | 1658 | RSS: Main: 200871936 10:15:50 INFO - PROCESS | 1658 | 10:15:52 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49256/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:15:52 INFO - PROCESS | 1658 | RSS: Main: 200503296 10:15:52 INFO - PROCESS | 1658 | 10:15:54 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49256/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:15:55 INFO - PROCESS | 1658 | RSS: Main: 200585216 10:15:55 INFO - PROCESS | 1658 | 10:15:57 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49256/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:15:58 INFO - PROCESS | 1658 | RSS: Main: 200495104 10:15:58 INFO - PROCESS | 1658 | 10:16:00 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49256/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:16:01 INFO - PROCESS | 1658 | RSS: Main: 200634368 10:16:01 INFO - PROCESS | 1658 | 10:16:04 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49256/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:16:05 INFO - PROCESS | 1658 | RSS: Main: 200941568 10:16:05 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49256/tests/kraken/driver.html?testName=ai-astar) 10:16:07 INFO - PROCESS | 1658 | RSS: Main: 202174464 10:16:07 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | __start_tp_report 10:16:07 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 10:16:07 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 10:16:07 INFO - PROCESS | 1658 | |i|pagename|runs| 10:16:07 INFO - PROCESS | 1658 | |0;ai-astar;105;111;115;116;116;116;117;133;159;117 10:16:07 INFO - PROCESS | 1658 | |1;audio-beat-detection;141;109;107;140;109;111;116;132;137;110 10:16:07 INFO - PROCESS | 1658 | |2;audio-dft;195;197;201;211;282;250;195;194;244;218 10:16:07 INFO - PROCESS | 1658 | |3;audio-fft;82;66;55;82;58;58;58;65;96;52 10:16:07 INFO - PROCESS | 1658 | |4;audio-oscillator;85;68;79;71;99;103;126;98;69;88 10:16:07 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;91;89;89;89;90;103;76;133;135;132 10:16:07 INFO - PROCESS | 1658 | |6;imaging-darkroom;96;104;141;95;96;95;96;90;154;155 10:16:07 INFO - PROCESS | 1658 | |7;imaging-desaturate;94;92;90;90;103;90;91;93;87;90 10:16:07 INFO - PROCESS | 1658 | |8;json-parse-financial;55;54;55;56;56;58;58;59;59;57 10:16:07 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;68;74;70;77;79;74;74;69;70;68 10:16:07 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;83;82;82;83;81;85;82;86;87;89 10:16:07 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;137;144;152;151;136;137;138;161;170;145 10:16:07 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;165;163;217;150;226;211;164;162;168;230 10:16:07 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;61;73;87;87;73;66;68;65;67;66 10:16:07 INFO - PROCESS | 1658 | __end_tp_report 10:16:07 INFO - PROCESS | 1658 | __start_cc_report 10:16:07 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,896 10:16:07 INFO - PROCESS | 1658 | __end_cc_report 10:16:07 INFO - PROCESS | 1658 | __startTimestamp1484244967258__endTimestamp 10:16:07 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | ------- Summary: start ------- 10:16:07 INFO - PROCESS | 1658 | Number of tests: 14 10:16:07 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:120.50 Median:116.50 stddev:15.22 (13.1%) stddev-sans-first:15.07 10:16:07 INFO - PROCESS | 1658 | Values: 105.0 111.0 115.0 116.0 116.0 116.0 117.0 133.0 159.0 117.0 10:16:07 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:121.20 Median:124.00 stddev:14.41 (11.6%) stddev-sans-first:13.38 10:16:07 INFO - PROCESS | 1658 | Values: 141.0 109.0 107.0 140.0 109.0 111.0 116.0 132.0 137.0 110.0 10:16:07 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:218.70 Median:214.50 stddev:30.19 (14.1%) stddev-sans-first:30.78 10:16:07 INFO - PROCESS | 1658 | Values: 195.0 197.0 201.0 211.0 282.0 250.0 195.0 194.0 244.0 218.0 10:16:07 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:67.20 Median:65.50 stddev:14.56 (22.2%) stddev-sans-first:14.42 10:16:07 INFO - PROCESS | 1658 | Values: 82.0 66.0 55.0 82.0 58.0 58.0 58.0 65.0 96.0 52.0 10:16:07 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:88.60 Median:82.00 stddev:18.34 (22.4%) stddev-sans-first:19.40 10:16:07 INFO - PROCESS | 1658 | Values: 85.0 68.0 79.0 71.0 99.0 103.0 126.0 98.0 69.0 88.0 10:16:07 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:102.70 Median:89.00 stddev:22.10 (24.8%) stddev-sans-first:23.03 10:16:07 INFO - PROCESS | 1658 | Values: 91.0 89.0 89.0 89.0 90.0 103.0 76.0 133.0 135.0 132.0 10:16:07 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:112.20 Median:95.00 stddev:26.56 (28.0%) stddev-sans-first:27.51 10:16:07 INFO - PROCESS | 1658 | Values: 96.0 104.0 141.0 95.0 96.0 95.0 96.0 90.0 154.0 155.0 10:16:07 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:92.00 Median:90.50 stddev:4.32 (4.8%) stddev-sans-first:4.52 10:16:07 INFO - PROCESS | 1658 | Values: 94.0 92.0 90.0 90.0 103.0 90.0 91.0 93.0 87.0 90.0 10:16:07 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:57.50 stddev:1.77 (3.1%) stddev-sans-first:1.76 10:16:07 INFO - PROCESS | 1658 | Values: 55.0 54.0 55.0 56.0 56.0 58.0 58.0 59.0 59.0 57.0 10:16:07 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:74.00 stddev:3.86 (5.2%) stddev-sans-first:3.77 10:16:07 INFO - PROCESS | 1658 | Values: 68.0 74.0 70.0 77.0 79.0 74.0 74.0 69.0 70.0 68.0 10:16:07 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.00 stddev:2.62 (3.1%) stddev-sans-first:2.76 10:16:07 INFO - PROCESS | 1658 | Values: 83.0 82.0 82.0 83.0 81.0 85.0 82.0 86.0 87.0 89.0 10:16:07 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:148.00 stddev:11.45 (7.7%) stddev-sans-first:11.55 10:16:07 INFO - PROCESS | 1658 | Values: 137.0 144.0 152.0 151.0 136.0 137.0 138.0 161.0 170.0 145.0 10:16:07 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.60 Median:189.50 stddev:31.22 (16.5%) stddev-sans-first:32.21 10:16:07 INFO - PROCESS | 1658 | Values: 165.0 163.0 217.0 150.0 226.0 211.0 164.0 162.0 168.0 230.0 10:16:07 INFO - PROCESS | 1658 | 10:16:07 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:70.50 stddev:9.01 (12.8%) stddev-sans-first:8.75 10:16:07 INFO - PROCESS | 1658 | Values: 61.0 73.0 87.0 87.0 73.0 66.0 68.0 65.0 67.0 66.0 10:16:07 INFO - PROCESS | 1658 | -------- Summary: end -------- 10:16:07 INFO - PROCESS | 1658 | 10:16:08 INFO - TEST-INFO | 1658: exit 0 10:16:08 INFO - TEST-OK | kraken | took 58994ms 10:16:08 INFO - SUITE-END | took 1171s 10:16:08 INFO - Completed test suite (00:19:31) 10:16:08 INFO - javascript benchmark 10:16:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7418.85495730872, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2991.9252018699535, 3617.113807117257, 3623.92752144957, 3649.0, 3679.0250583595353, 5910.290765108182, 5956.166136740854, 5976.52187824974, 6004.189434426351, 6005.3394126646035, 5956.940430595692, 5980.8504787380225, 6045.183900173475, 6051.0, 6064.818055458343, 5394.298741163643, 5397.001554712371, 5406.756695948692, 5469.644473109255, 5557.360903496089, 5446.509814116729, 5464.23500709901, 5484.862878428051, 5488.231647569343, 5504.559635229172, 5340.732963351837, 5364.973175134119, 5457.863553411157, 5464.890702185954, 5465.9180112298345, 4376.606105450493, 5383.327084114505, 5407.0, 5421.2952316199035, 5446.782128714846, 4497.640188784922, 5320.095583750733, 5355.437679043722, 5360.571154307646, 5432.91850622241, 6041.879162416771, 6089.969550152266, 6121.265448146207, 6142.8771424571705, 6146.84632884177, 5150.665206761549, 6198.62808231503, 6212.440880320748, 6226.87546249077, 6263.123162757218, 6316.08416779569, 6319.778807741707, 6328.430441260262, 6374.298827129012, 6386.776462823779, 5721.284839395075, 6084.087386891957, 6096.634201947897, 6099.207103076616, 6104.786332478342, 6350.6189628621505, 6357.332480089524, 6410.711517981702, 6416.262129855127, 6441.64570948593, 6297.811065668037, 6310.305866354697, 6321.051842223703, 6328.0, 6333.651649159248, 6211.13044173807, 6275.780347687901, 6378.872422551523, 6385.680715964183, 6395.104685344056, 6215.844603884939, 6246.906296405557, 6251.874962500816, 6315.336889626523, 6325.051242313598, 6270.592411493239, 6277.372262773686, 6306.369363063749, 6315.147455093595, 6324.272708638474, 4277.0, 4348.804078878334, 5008.373953255843, 5059.620528460307, 5094.439611642716, 4463.8214471421425, 4544.159330523864, 4568.0, 4587.059652771174, 4592.7933243004145, 6072.1498990140535, 6088.360722124202, 6140.232470941132, 6172.722227499773, 6197.101420294032, 6398.488120950313, 6405.135306733531, 6406.615603063831, 6407.839804004844, 6422.614643121427, 6016.789412370546, 6066.150738896558, 6109.070667813081, 6109.450149486567, 6142.815715528542, 5617.185508101303, 5619.0, 5641.858953526113, 5648.830535083954, 5682.289713785777, 6496.187976502937, 6519.543631945813, 6561.179852518435, 6569.57297775643, 6580.967095164494, 6192.690365481618, 6193.907091393543, 6266.905996410149, 6272.059191121369, 6347.90478142819, 6525.347465253437, 6544.149260596282, 6550.443212326815, 6551.148350714377, 6562.50781191403, 5317.6543524672325, 6300.527460440394, 6312.3056463788025, 6331.0, 6334.239891213084, 5554.527865131509, 5813.505852002458, 6264.59280146789, 6305.306416294112, 6365.586236894587, 5129.589632829439, 6250.874982500415, 6277.0, 6295.811125666238, 6307.148534947907, 6293.056041593798, 6298.937010630018, 6303.653299068412, 6321.620702757849, 6322.399372059647, 5970.58205925581, 6134.681512609229, 6204.410580994799, 6252.09344645033, 6287.622742635456, 6215.533834962305, 6240.188775459162, 6255.31191568918, 6293.622382657055, 6317.873642527215, 6240.906386404298, 6285.2457705075685, 6296.401841825203, 6297.559170857996, 6319.083732858801], "value": 5853.161044861381, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.08508556969076, 523.6046784677568, 535.3682654467726, 538.477676653646, 544.4093158922572, 40432.0, 42422.0, 42453.57546424535, 42475.0, 42521.0, 44356.0, 44651.55348446523, 44800.77599611998, 44913.55086449134, 44957.55042449575, 9947.602095916154, 10036.046575575308, 10047.296689231756, 10088.243381746363, 10102.0, 9902.801943961116, 10006.0, 10017.248706347016, 10020.19838412929, 10041.497925103715, 10013.299069065168, 10019.0, 10033.498325083752, 10037.498125093716, 10058.245631577623, 9977.75055623608, 10038.598456061749, 10041.19670426364, 10046.095851373411, 10076.69769906904, 8601.268892144175, 10053.497325133714, 10058.698239052841, 10067.69796906094, 10087.394756314645, 42512.78743606262, 42521.93695157615, 42550.36174457386, 43079.0, 43081.0, 2910.286979689968, 3552.6802587767224, 3627.093226693327, 3884.242572698325, 4382.868513944587, 31479.055628330956, 31515.84242078798, 31992.680073199204, 31994.0, 32003.359932801213, 3355.39602871483, 3381.847816848248, 3441.346144232613, 3442.5868895732424, 3443.517907492953, 520046.0, 526267.0, 526699.0, 527834.3608281972, 529712.0, 31837.0, 52198.73900630473, 57777.711111445016, 59563.0, 59819.0, 33732.98801035973, 35227.0, 35278.35412344033, 36061.196940152884, 36186.638133618326, 15724.764128538081, 15931.884768066124, 15952.76070858938, 15974.76037859433, 15980.520584382488, 13134.211947283193, 13391.598252052454, 13449.125806822722, 13474.393652285671, 13516.324183790772, 6923.446124310144, 7837.177096404909, 8050.27547520726, 8063.0, 8083.434159608771, 3411.965880341165, 3428.5885693716914, 3440.690337869604, 3441.0881116504147, 3462.774919630216, 897.9281657467387, 980.7352014955922, 1057.8889216632297, 1102.972425689348, 1187.6496433552122, 25489.107881224067, 31809.0, 31953.68046319507, 32264.83867580694, 32324.83837580797, 1060.8860696268887, 1078.7217147680024, 1081.291753901196, 1136.6078702847503, 1176.253079294654, 15546.37814487408, 15882.76175857363, 16083.35666573324, 16179.757303640456, 16411.343546258016, 595448.0, 698901.0, 709408.0, 711134.0, 717252.827471729, 57252.713736431055, 58569.0, 58756.41243587595, 59134.40865591289, 59152.0, 1381.3645722967547, 1393.6515871032243, 1415.950441734555, 1435.6195608163846, 1436.2100844535464, 29962.850185748932, 30279.788808447414, 31917.0, 32241.51637725389, 32353.0, 57840.843183136945, 58640.70679646575, 58699.4130058711, 58845.70577147087, 58868.11697824451, 657263.0, 659892.0, 661142.0, 665226.6738666276, 665657.0, 44656.0, 44675.55324446714, 44702.3294650587, 44955.32567011432, 45020.77489612531, 47728.045439091715, 48932.40813657976, 49582.504174959235, 50004.24993625171, 50196.49803501918, 40881.0, 40918.0, 41361.0, 41468.0, 42902.0, 41819.581804181566, 42021.579784201764, 42290.788546057076, 42554.361684574134, 42595.14809703851, 39460.60539394684, 40493.79753101333, 40703.0, 40956.38565421462, 40962.0, 34702.47946280858, 36035.459468107474, 36231.429012853456, 36429.783654922205, 36781.71264005747, 26917.461650766483, 26940.0, 26971.190864274104, 27042.459150817267, 27111.72888271092], "value": 19886.95141516021, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.0434961985028, 387.1154412168196, 387.1753165756937, 394.29421335808894, 414.5419311660615, 20314.984250787456, 20536.691949620767, 21289.893550532226, 21467.892660536694, 21481.248156314534, 20689.793102069012, 20770.169193232254, 20970.37088887336, 21302.46743831405, 21811.127554897786, 5250.527452529272, 5557.166425036236, 5566.359868615121, 5607.214989901417, 5619.719014049301, 5414.702191379472, 5551.139573366135, 5568.470995255465, 5599.7480113394995, 5847.298324201101, 5996.0, 6067.211262535864, 6089.756409743627, 6117.2659280886355, 6162.014077747562, 5443.945560544384, 5502.80740174092, 5662.631928924648, 6113.694315284218, 6116.235470566179, 4746.501617330166, 4896.363472748555, 5125.821061155955, 5136.794528218867, 5317.973410132963, 13502.864971350356, 19059.90470047655, 25082.372940676447, 25707.61438578423, 26420.73579264202, 2218.9556208875733, 2252.583272094668, 2259.378670865509, 2324.1052194905083, 2355.7526459721653, 10200.285979981405, 10296.948515257376, 10405.011523905292, 10624.946875265574, 10684.839727404094, 1940.4469726127986, 1993.2027189124321, 2029.350607805503, 2038.9184432622826, 2078.511549785799, 120130.0, 121448.0, 124574.37712811469, 131550.0, 132116.0, 10026.298159128792, 12006.759864802654, 12012.459439325252, 12218.572349967708, 12362.01103911703, 14164.22096784666, 14737.705245895022, 14741.778873316909, 15050.924745376422, 15063.924680376527, 8913.0, 8987.820243595092, 9045.457272563668, 9053.502057386775, 9151.862722059175, 7553.509021913558, 9016.684416045407, 9055.139761722627, 9060.682876099305, 9165.083491650781, 4622.815087396534, 4658.836940707127, 4671.346011558385, 4679.976600116977, 4746.216874215785, 1946.8734532255357, 1986.9205231790856, 1996.452749119444, 2004.959900801976, 2008.347287131688, 801.294860522748, 864.4553931023415, 930.6696122876417, 931.9161275485103, 952.5332587032304, 17828.821711782977, 20274.89862550678, 20444.386668399973, 21534.56930861374, 21594.136234550755, 923.215267023025, 939.7603611079131, 941.7410212191702, 944.6788092048737, 981.2444417798256, 11018.1185505161, 11339.206255562196, 11344.262622929484, 11365.772684546262, 11372.943135284271, 585892.0, 602372.0, 632950.0, 637187.0, 641247.0, 120378.3981080089, 135336.0, 139090.0, 146704.0, 149052.0, 423.9046214601752, 441.6510956344452, 446.475391415086, 448.2514201283929, 448.62988034871364, 10951.890481095086, 11419.486123124645, 11636.592719254786, 11669.76660466803, 11743.17797754149, 53948.73025634847, 57208.141877871036, 58005.0, 58932.0, 59901.700491497264, 74374.62812686118, 76322.6183869077, 76864.61567692125, 80255.0, 80707.19292806997, 8196.262336389753, 8326.29226515753, 8339.332853371838, 8349.83300334002, 8382.538960357238, 12633.620991370273, 13156.897926634616, 13268.668283292609, 13358.198508089547, 13442.865571344162, 11456.0, 11488.253263537843, 11521.366324852213, 11651.359175245443, 11678.474468649101, 8843.955780221058, 11015.944920275348, 11071.723206919569, 11165.330080195214, 11279.661610151708, 9599.952000239955, 9642.566084526074, 10663.613730215022, 11210.775784484427, 11479.942600286946, 5851.64890106595, 8231.629576668944, 8445.577721114043, 8487.872681909652, 8697.565121744014, 9525.995409639701, 9687.41875487473, 10090.54592543352, 11395.886041139482, 11615.709607259549], "value": 9298.413675841814, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.7786943023827, 1466.4280930437128, 1778.2531336838526, 1917.2331067572977, 1978.7823339432668, 8301.792455188608, 8545.786355341119, 8789.472631642106, 9328.30037747169, 9339.673111441092, 8763.298936085113, 9029.593668284926, 9038.54807259636, 9117.997020327775, 9125.361224714272, 2420.745821688728, 2428.86641234732, 2429.307647320514, 2448.51029794041, 2477.6531285620026, 2448.6327050942323, 2455.1407007547305, 2480.677511923456, 2487.7636624520733, 2490.816861990549, 2455.4107014316523, 2468.7654672806143, 2474.64117702933, 2481.0944005438064, 2481.6153496208117, 2404.2426635609727, 2432.756724327562, 2434.476587533671, 2443.2303824295377, 2459.655648209234, 2397.724261709908, 2425.866577338246, 2446.0460420436043, 2458.3485376375274, 2462.4090218347646, 9163.633454661805, 9183.90816091844, 9199.58601862911, 9357.251419886392, 9405.76485587859, 5459.890802183934, 5550.611457197998, 5615.2700148980375, 5637.689927054011, 5664.433556644344, 3297.1262615406936, 3318.3363327334387, 3712.9443058354145, 3756.229972855558, 3764.7176461765202, 1077.8329358949375, 1111.188832152525, 1118.3178261260625, 1138.3397629374942, 1139.9316041037482, 11996.340201288926, 12036.0, 12041.397930103547, 12099.879001209963, 12116.575919842764, 4811.206150985118, 4873.269009648581, 4900.485449027872, 4989.027139707722, 4996.125678006334, 10266.691999240034, 10297.02178293061, 10353.119984801377, 10360.637377691744, 10361.963803619728, 5498.285222920995, 5503.6147469677535, 5568.3318001839225, 5579.0, 5627.015272327327, 4605.240135377692, 4718.551737584924, 4920.72935988524, 5031.899362012739, 5050.116229659869, 4064.7967601619803, 4069.1861627674584, 4124.52567954689, 4165.500139983161, 4192.685548583869, 1164.0861923390098, 1328.9667758305927, 1332.1141440941665, 1339.5378594384922, 1345.5156143788229, 928.2898582584328, 928.9767755806159, 942.5569982108398, 943.0, 965.2567523007245, 5831.825045248649, 5920.437558432028, 5932.228810254639, 6066.605670631395, 6150.477209437248, 447.39601537923545, 447.61057879644915, 453.26797222486107, 467.6562726396096, 477.11257061864904, 3697.8721489945287, 3812.542494900574, 4055.4727885374714, 4117.382392641128, 4119.958800412018, 10550.366977981346, 10839.295445795999, 10978.505967231495, 10997.61508347204, 11095.83356249657, 9330.253579713606, 10701.83947240792, 10836.241463097665, 11039.72400689973, 11079.113670906308, 8337.082920878698, 8508.191721786545, 8508.787280318042, 8602.827943441096, 8617.267532259702, 5876.471117599436, 5995.130706047686, 6048.818535443944, 6060.272767267956, 6145.170401995673, 8598.39811213209, 9995.450250236307, 10098.54556544972, 10432.165426765689, 10525.947370263406, 10604.310719803188, 10623.521941512496, 10654.8401773975, 10667.57329706834, 10716.089132423831, 7055.435565154878, 7253.3834624057545, 7519.1728909818785, 7732.536047836923, 7781.805454863674, 8477.830443390974, 8608.526531040852, 8611.526366049928, 8752.0, 8775.0, 7671.462997590115, 7777.766666999999, 7821.335186508983, 7856.882146767917, 7943.4042446817875, 7575.659095340613, 7689.461737678309, 7702.730404435818, 7717.652705628372, 7724.7682569523, 7159.928400715926, 7291.34377905991, 7296.379807716404, 7301.123865136217, 7306.817329566591, 4962.379702537183, 5027.824026159067, 5077.416097148875, 5134.640575159703, 5272.657277276965, 5037.748112594429, 5129.8974020518635, 5149.150390185728, 5257.0, 5291.021161085211], "value": 4649.263570784312, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [280.7711714952315, 312.3035630543887, 313.4405086919848, 316.18109097437633, 318.00306040562845, 5440.292761940942, 5469.042917489438, 5514.95215908977, 5575.041720378526, 5586.217929489874, 4433.379326894237, 4528.569785870345, 4663.766811659412, 5396.138332275368, 5413.296271484702, 3953.505811773521, 4079.816853112594, 4090.3046482098116, 4127.855525056623, 4167.499900012002, 3689.262147570483, 4079.4900637420324, 4117.794110294488, 4134.834606615732, 4184.8535301264465, 3432.4508078707413, 4058.8579399721016, 4149.502059752834, 4182.2681030819485, 4195.4545909031785, 4029.738067025634, 4090.380013687878, 4167.2487775671, 4175.290200665894, 4209.810558524843, 3151.0, 3584.6953008994265, 3606.6934310583633, 3608.314420260142, 3623.3296840084668, 4552.361149986003, 4646.883827904296, 5257.553107985826, 5291.232771248147, 5379.919301210485, 4631.907361852744, 4806.567408933178, 4809.663323567352, 4818.638602104821, 4837.782299796518, 4973.875653108665, 5306.310179676618, 5702.800401985952, 5716.114002329646, 5828.0, 3951.308521008812, 3984.8406063757416, 3992.602589093823, 4014.879553613367, 4026.6577340926056, 4848.612111031109, 5828.533717302648, 5867.237259156283, 6011.549133814981, 6041.969790151021, 3312.850921708529, 3444.776089554171, 4046.3728122141115, 4057.431959525669, 4088.7137900347284, 3188.601424821897, 3858.8070596470066, 4122.330456165258, 4167.83328666856, 4176.2691528982305, 4535.435274830178, 4790.7365094920115, 4815.0868724421925, 4837.7097374157665, 4848.903021939542, 4802.927956080662, 5320.787168513216, 5800.796972105956, 5861.9706901465215, 5898.8230353089475, 1142.5429828068839, 1293.482606957206, 1388.9097058808786, 1401.6355747505722, 1401.950931717385, 851.7529916324197, 877.394597727566, 887.8046829697456, 908.6229214875752, 933.6545478172984, 4563.361129441881, 5173.301604283449, 5273.209018647157, 5450.345958485007, 5471.206675032098, 5964.821055368259, 5973.402659733992, 6020.608660437058, 6021.909671354933, 6059.6364218147055, 4929.630277729185, 4986.401631804207, 5076.593872490192, 5304.965531721277, 5542.418046105182, 2824.331007198178, 3091.335362896988, 3100.0, 3117.579126817896, 3158.8104713717253, 5891.263592050994, 6113.187933550616, 6130.908036379458, 6162.425793040026, 6178.382161783876, 4728.456227533878, 5437.32033495813, 5753.4821866031425, 6185.536084793658, 6255.78104766331, 5151.639385242997, 5202.9677000325055, 5824.0303688719405, 6176.66028368444, 6266.749330026839, 5511.0, 5514.283143191359, 5883.235179426647, 6119.571629985859, 6144.754209831735, 5103.08531939217, 5375.00562395958, 5954.1244507979845, 5963.793396443344, 5983.34183239852, 5437.537809286255, 5682.573806964577, 5937.0, 5981.282246130492, 6306.590071645328, 3753.268112717994, 3973.2252210818615, 4001.8999525010945, 4012.678985681197, 4021.256067627381, 4989.476105008922, 5963.850903727442, 5981.7906373278465, 6018.3981601841215, 6029.306629737636, 4821.831235906727, 5140.56305214061, 5831.658718494685, 5855.882882342415, 5856.472917437451, 5289.973550132225, 5428.131498960148, 5819.272590926134, 5874.236349274567, 5952.255968003999, 5735.082386818089, 5751.504608801658, 5780.046292361713, 5793.420657934342, 5802.622829516068, 5133.768980395966, 5351.518363347317, 5581.302337207849, 5677.659340439587, 5814.273215848019, 5702.0, 5711.571632127524, 5743.253377061123, 5758.568107391879, 5784.021229067118], "value": 4201.1013201854985, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.8761822871883, 736.7458226911717, 763.3435245688706, 768.861604911116, 859.8280343931216, 15670.764938525917, 15963.122028288439, 16111.0, 16607.418740344092, 16664.583385415357, 14815.0, 14991.92504037481, 15000.324985375657, 15003.324850381732, 15019.32413041413, 710.1300906389657, 735.7939776862487, 871.0897112517434, 874.8950125984875, 887.8978917424514, 728.5810658871144, 833.8659423184464, 866.2030931542996, 870.060334838373, 872.0668884293776, 911.5214512380986, 931.7297983584787, 932.1703683721494, 933.169479163545, 933.6685476655791, 784.7175016993878, 905.5743800413795, 927.0544045074054, 928.9024652411467, 935.5649622925339, 607.3683369295927, 653.8332725155102, 683.1597135523334, 689.7034275261635, 691.3466773898667, 14843.480478183319, 14917.477888273967, 14919.701605967819, 14924.029938053991, 14938.925305373403, 14980.850191498055, 14998.400063997427, 15059.774103388458, 15144.848551514455, 15213.543593692208, 11895.405229738479, 11899.405029748477, 11945.109780439121, 12059.155859089868, 12492.0, 3989.40158976153, 4028.3151864182864, 4048.817803198863, 4071.857484988011, 4129.504459464883, 115611.0, 120709.0, 121851.3907430466, 122750.77249227393, 124033.0, 10959.6797425225, 11325.603603873826, 11391.544338226378, 11745.588904388476, 11783.116266280063, 23653.7634623655, 23669.171578994654, 23912.40218994539, 24026.279211623678, 24030.038798447866, 7738.61306934651, 7893.763187104396, 8346.582670866432, 8504.404691671647, 8536.823110107007, 4417.668674849335, 4608.953910460919, 4648.907021859543, 4656.32483289921, 4697.553732395417, 3489.616142224353, 3562.2163124112653, 3624.6919011883756, 3626.437902125175, 3934.15415685629, 736.8231624410103, 741.3550211316082, 743.0934260202545, 748.7566540874129, 766.8082979255187, 625.9029850373199, 646.7380710812129, 677.6577828196729, 682.952193346471, 686.9072675188886, 9339.486328251876, 9530.332876698565, 9720.75698107553, 9768.95115524432, 9827.950860245795, 634.6636282770139, 670.9194896612438, 673.4006734006726, 701.8140192848899, 713.8715031294313, 4386.473623165177, 4526.162659907927, 4754.263089221176, 4838.693661936884, 4856.514348565186, 151431.2428437851, 152228.0, 152721.0, 152776.0, 155496.0, 79274.0, 83614.58192708998, 118068.40965795115, 124223.0, 135338.0, 113783.0, 116993.0, 117972.23041654383, 118694.21958670627, 119258.40370798264, 8985.640574377214, 9163.86254206174, 9168.266538677024, 9223.907760922304, 9318.95340523293, 57256.0, 58353.4164658348, 58964.41035589589, 59277.407225927185, 60364.396356035875, 84061.57969210115, 86968.56515717381, 88368.0, 89456.0, 90992.5450372744, 11876.168668193144, 12002.879971200176, 12087.274763514217, 12148.757024859276, 12350.876491235333, 12682.0, 12866.134410510236, 13249.0, 13541.661458463617, 13672.0, 12791.0, 12795.36023198855, 12817.23096614206, 12910.870891290966, 13142.34288285601, 12641.0, 12724.872751272738, 12788.744225115632, 13096.083274170716, 13162.539311124065, 12514.31171285588, 12518.311492867615, 12618.9904807617, 12631.93684031574, 12745.426455809325, 8702.434341767765, 10817.918208179119, 10842.403667798031, 11007.834882476609, 11372.033377162703, 11020.559177633126, 11037.724056898642, 11209.215354925076, 11254.943725281648, 11286.081366167935], "value": 7886.1140895085655, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 111.0, 115.0, 116.0, 116.0, 116.0, 117.0, 133.0, 159.0, 117.0], "value": 120.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 107.0, 140.0, 109.0, 111.0, 116.0, 132.0, 137.0, 110.0], "value": 121.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 197.0, 201.0, 211.0, 282.0, 250.0, 195.0, 194.0, 244.0, 218.0], "value": 218.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 66.0, 55.0, 82.0, 58.0, 58.0, 58.0, 65.0, 96.0, 52.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 68.0, 79.0, 71.0, 99.0, 103.0, 126.0, 98.0, 69.0, 88.0], "value": 88.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 89.0, 89.0, 90.0, 103.0, 76.0, 133.0, 135.0, 132.0], "value": 102.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 104.0, 141.0, 95.0, 96.0, 95.0, 96.0, 90.0, 154.0, 155.0], "value": 112.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 92.0, 90.0, 90.0, 103.0, 90.0, 91.0, 93.0, 87.0, 90.0], "value": 92.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 56.0, 56.0, 58.0, 58.0, 59.0, 59.0, 57.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 70.0, 77.0, 79.0, 74.0, 74.0, 69.0, 70.0, 68.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 82.0, 83.0, 81.0, 85.0, 82.0, 86.0, 87.0, 89.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 144.0, 152.0, 151.0, 136.0, 137.0, 138.0, 161.0, 170.0, 145.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 217.0, 150.0, 226.0, 211.0, 164.0, 162.0, 168.0, 230.0], "value": 185.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 73.0, 87.0, 87.0, 73.0, 66.0, 68.0, 65.0, 67.0, 66.0], "value": 71.3, "unit": "score"}]}]} 10:16:08 INFO - Return code: 0 10:16:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:16:08 ERROR - # TBPL SUCCESS # 10:16:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:16:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:16:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:16:08 INFO - 2017-01-12 10:16:08.604 screenresolution[1663:10420] starting screenresolution argv=screenresolution get 10:16:08 INFO - 2017-01-12 10:16:08.607 screenresolution[1663:10420] Display 0: 1600x1200x32@60 10:16:08 INFO - 2017-01-12 10:16:08.613 screenresolution[1664:10423] starting screenresolution argv=screenresolution list 10:16:08 INFO - Available Modes on Display 0 10:16:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:16:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:16:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:16:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:16:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:16:08 INFO - Intel Iris: 10:16:08 INFO - Chipset Model: Intel Iris 10:16:08 INFO - Type: GPU 10:16:08 INFO - Bus: Built-In 10:16:08 INFO - VRAM (Dynamic, Max): 1536 MB 10:16:08 INFO - Vendor: Intel (0x8086) 10:16:08 INFO - Device ID: 0x0a2e 10:16:08 INFO - Revision ID: 0x0009 10:16:08 INFO - Displays: 10:16:08 INFO - Display: 10:16:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:16:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:16:08 INFO - Display Serial Number: 1600x1200 60 10:16:08 INFO - Main Display: Yes 10:16:08 INFO - Mirror: Off 10:16:08 INFO - Online: Yes 10:16:08 INFO - Rotation: Supported 10:16:08 INFO - Return code: 0 10:16:08 INFO - Running post-action listener: _resource_record_post_action 10:16:08 INFO - [mozharness: 2017-01-12 18:16:08.847469Z] Finished run-tests step (success) 10:16:08 INFO - Running post-run listener: _resource_record_post_run 10:16:08 INFO - Total resource usage - Wall time: 1194s; CPU: 54.0%; Read bytes: 37467136; Write bytes: 497637376; Read time: 257; Write time: 1610 10:16:08 INFO - TinderboxPrint: CPU usage
54.2% 10:16:08 INFO - TinderboxPrint: I/O read bytes / time
37,467,136 / 257 10:16:08 INFO - TinderboxPrint: I/O write bytes / time
497,637,376 / 1,610 10:16:08 INFO - TinderboxPrint: CPU idle
2,187.4 (45.8%) 10:16:08 INFO - TinderboxPrint: CPU system
969.1 (20.3%) 10:16:08 INFO - TinderboxPrint: CPU user
1,621.2 (33.9%) 10:16:08 INFO - TinderboxPrint: Swap in / out
233,172,992 / 0 10:16:08 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 190661120; Write bytes: 187790848; Read time: 17546; Write time: 421 10:16:08 INFO - run-tests - Wall time: 1172s; CPU: 54.0%; Read bytes: 35480576; Write bytes: 298873344; Read time: 228; Write time: 1154 10:16:09 INFO - Running post-run listener: _upload_blobber_files 10:16:09 INFO - Blob upload gear active. 10:16:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:16:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:16:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:16:09 INFO - (blobuploader) - INFO - Open directory for files ... 10:16:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:16:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:16:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:16:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:16:11 INFO - (blobuploader) - INFO - Done attempting. 10:16:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:16:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:16:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:16:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:16:15 INFO - (blobuploader) - INFO - Done attempting. 10:16:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:16:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:16:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:16:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:16:18 INFO - (blobuploader) - INFO - Done attempting. 10:16:18 INFO - (blobuploader) - INFO - Iteration through files over. 10:16:18 INFO - Return code: 0 10:16:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:16:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:16:18 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00ee2ed0291829736b09813a0513c50ded93ae773fcca5ad6e5db10ea9ecb4ee649721a4079cf59451a4e3d95ec5d9ccc01ed0733950ca4193350151c59c0395", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7efdb3f7772f224c585f7b2e56e1ebea10d2e2edb669cc741908e9801108c6a7c4b38d4289664e1a6f7c1d9939c3fd20d92a7b30bcdeae375965885bc15fce92", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de19e12b1eb692bb4ffbc09cdb515b8de741f9776e9678f56855b236b5967d704e8d784e1246b0335f3aa57391627aac711fa85d78e2dfc4f4971573ac58906f"} 10:16:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:16:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:16:18 INFO - Contents: 10:16:18 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00ee2ed0291829736b09813a0513c50ded93ae773fcca5ad6e5db10ea9ecb4ee649721a4079cf59451a4e3d95ec5d9ccc01ed0733950ca4193350151c59c0395", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7efdb3f7772f224c585f7b2e56e1ebea10d2e2edb669cc741908e9801108c6a7c4b38d4289664e1a6f7c1d9939c3fd20d92a7b30bcdeae375965885bc15fce92", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de19e12b1eb692bb4ffbc09cdb515b8de741f9776e9678f56855b236b5967d704e8d784e1246b0335f3aa57391627aac711fa85d78e2dfc4f4971573ac58906f"} 10:16:18 INFO - Running post-run listener: copy_logs_to_upload_dir 10:16:18 INFO - Copying logs to upload dir... 10:16:18 INFO - mkdir: /builds/slave/test/build/upload/logs 10:16:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.746685 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-01-12 10:16:18.386645) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 10:16:18.393898) ========= 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.ewectKOCOV/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.ZiLLZpcoq8/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00ee2ed0291829736b09813a0513c50ded93ae773fcca5ad6e5db10ea9ecb4ee649721a4079cf59451a4e3d95ec5d9ccc01ed0733950ca4193350151c59c0395", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7efdb3f7772f224c585f7b2e56e1ebea10d2e2edb669cc741908e9801108c6a7c4b38d4289664e1a6f7c1d9939c3fd20d92a7b30bcdeae375965885bc15fce92", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de19e12b1eb692bb4ffbc09cdb515b8de741f9776e9678f56855b236b5967d704e8d784e1246b0335f3aa57391627aac711fa85d78e2dfc4f4971573ac58906f"} build_url:https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011094 build_url: 'https://queue.taskcluster.net/v1/task/Rx029QV5SuCcFihWcLoZug/artifacts/public/build/firefox-51.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00ee2ed0291829736b09813a0513c50ded93ae773fcca5ad6e5db10ea9ecb4ee649721a4079cf59451a4e3d95ec5d9ccc01ed0733950ca4193350151c59c0395", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7efdb3f7772f224c585f7b2e56e1ebea10d2e2edb669cc741908e9801108c6a7c4b38d4289664e1a6f7c1d9939c3fd20d92a7b30bcdeae375965885bc15fce92", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de19e12b1eb692bb4ffbc09cdb515b8de741f9776e9678f56855b236b5967d704e8d784e1246b0335f3aa57391627aac711fa85d78e2dfc4f4971573ac58906f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 10:16:18.430925) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 10:16:18.431247) ========= 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.ewectKOCOV/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.ZiLLZpcoq8/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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.005244 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 10:16:18.480303) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 10:16:18.483185) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 10:16:18.483527) ========= ========= Total master_lag: 1.02 =========