builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0035 starttime: 1494430817.7 results: success (0) revision: ebbcdaa5b5802ecd39624dd2acbdda8547b8384d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-10 08:40:17.697274) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-10 08:40:17.697669) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-10 08:40:17.698061) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-10 08:40:17.725524) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 08:40:17.725912) ========= 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.gY6bb4FO7U/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.wsZedlf1cF/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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-05-10 08:40:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.2M=0.001s 2017-05-10 08:40:18 (15.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.487292 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 08:40:18.238141) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 08:40:18.238541) ========= 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.gY6bb4FO7U/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.wsZedlf1cF/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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.028058 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 08:40:18.288728) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-10 08:40:18.289111) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ebbcdaa5b5802ecd39624dd2acbdda8547b8384d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev ebbcdaa5b5802ecd39624dd2acbdda8547b8384d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gY6bb4FO7U/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.wsZedlf1cF/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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-05-10 08:40:18,391 truncating revision to first 12 chars 2017-05-10 08:40:18,391 Setting DEBUG logging. 2017-05-10 08:40:18,391 attempt 1/10 2017-05-10 08:40:18,391 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ebbcdaa5b580?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-10 08:40:19,840 unpacking tar archive at: mozilla-central-ebbcdaa5b580/testing/mozharness/ program finished with exit code 0 elapsedTime=1.723128 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-10 08:40:20.035561) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-10 08:40:20.035908) ========= script_repo_revision: ebbcdaa5b5802ecd39624dd2acbdda8547b8384d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-10 08:40:20.036345) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-10 08:40:20.036620) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-10 08:40:20.058493) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-05-10 08:40:20.058975) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gY6bb4FO7U/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.wsZedlf1cF/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 08:40:20 INFO - MultiFileLogger online at 20170510 08:40:20 in /builds/slave/test 08:40:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:40:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:40:20 INFO - {'append_to_log': False, 08:40:20 INFO - 'base_work_dir': '/builds/slave/test', 08:40:20 INFO - 'blob_upload_branch': 'Firefox', 08:40:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:40:20 INFO - 'branch': 'Firefox', 08:40:20 INFO - 'buildbot_json_path': 'buildprops.json', 08:40:20 INFO - 'config_files': ('talos/mac_config.py',), 08:40:20 INFO - 'default_actions': ('clobber', 08:40:20 INFO - 'read-buildbot-config', 08:40:20 INFO - 'download-and-extract', 08:40:20 INFO - 'populate-webroot', 08:40:20 INFO - 'create-virtualenv', 08:40:20 INFO - 'install', 08:40:20 INFO - 'run-tests'), 08:40:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:40:20 INFO - 'download_minidump_stackwalk': True, 08:40:20 INFO - 'download_symbols': 'ondemand', 08:40:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:40:20 INFO - 'tooltool.py': '/tools/tooltool.py', 08:40:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:40:20 INFO - '/tools/misc-python/virtualenv.py')}, 08:40:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:40:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:40:20 INFO - 'gecko_profile': False, 08:40:20 INFO - 'gecko_profile_interval': 0, 08:40:20 INFO - 'installer_path': 'installer.exe', 08:40:20 INFO - 'log_level': 'info', 08:40:20 INFO - 'log_name': 'talos', 08:40:20 INFO - 'log_to_console': True, 08:40:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:40:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:40:20 INFO - 'opt_config_files': (), 08:40:20 INFO - 'pip_index': False, 08:40:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:40:20 INFO - 'cmd': ('bash', 08:40:20 INFO - '-c', 08:40:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:40:20 INFO - 'enabled': True, 08:40:20 INFO - 'halt_on_failure': False, 08:40:20 INFO - 'name': 'check_screen_resolution'},), 08:40:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:40:20 INFO - 'cmd': ('bash', 08:40:20 INFO - '-c', 08:40:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:40:20 INFO - 'enabled': True, 08:40:20 INFO - 'halt_on_failure': False, 08:40:20 INFO - 'name': 'check_screen_resolution'},), 08:40:20 INFO - 'run_cmd_checks_enabled': True, 08:40:20 INFO - 'suite': 'dromaeojs-e10s', 08:40:20 INFO - 'system_bits': '32', 08:40:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:40:20 INFO - 'title': 't-yosemite-r7-0035', 08:40:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:40:20 INFO - 'use_talos_json': True, 08:40:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:40:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:40:20 INFO - 'work_dir': 'build'} 08:40:20 INFO - [mozharness: 2017-05-10 15:40:20.276441Z] Running clobber step. 08:40:20 INFO - Running pre-action listener: _resource_record_pre_action 08:40:20 INFO - Running main action method: clobber 08:40:20 INFO - rmtree: /builds/slave/test/build 08:40:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:40:22 INFO - Running post-action listener: _resource_record_post_action 08:40:22 INFO - [mozharness: 2017-05-10 15:40:22.973057Z] Finished clobber step (success) 08:40:22 INFO - [mozharness: 2017-05-10 15:40:22.973252Z] Running read-buildbot-config step. 08:40:22 INFO - Running pre-action listener: _resource_record_pre_action 08:40:22 INFO - Running main action method: read_buildbot_config 08:40:22 INFO - Using buildbot properties: 08:40:22 INFO - { 08:40:22 INFO - "project": "", 08:40:22 INFO - "product": "firefox", 08:40:22 INFO - "who": "cbook@mozilla.com", 08:40:22 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 08:40:22 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:40:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:40:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:40:22 INFO - "stage_platform": "macosx64", 08:40:22 INFO - "basedir": "/builds/slave/test", 08:40:22 INFO - "buildnumber": 151, 08:40:22 INFO - "platform": "yosemite_r7", 08:40:22 INFO - "parent_task_id": "E_59EUdWTLWqPg30BD7Xiw", 08:40:22 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:40:22 INFO - "slavebuilddir": "test", 08:40:22 INFO - "taskId": "JrN7o1gTTUOnLhK1vM-OOA", 08:40:22 INFO - "branch": "mozilla-central", 08:40:22 INFO - "script_repo_revision": "production", 08:40:22 INFO - "revision": "ebbcdaa5b5802ecd39624dd2acbdda8547b8384d", 08:40:22 INFO - "slavename": "t-yosemite-r7-0035", 08:40:22 INFO - "repo_path": "mozilla-central" 08:40:22 INFO - } 08:40:22 INFO - Finding installer, test and symbols from parent task. 08:40:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JrN7o1gTTUOnLhK1vM-OOA'}, attempt #1 08:40:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JrN7o1gTTUOnLhK1vM-OOA'}, attempt #1 08:40:24 INFO - Task dependencies: MY4JQSQqRiiFFVj12C406Q 08:40:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw'}, attempt #1 08:40:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:40:24 INFO - Set test_url: https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/test_packages.json 08:40:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/target.crashreporter-symbols.zip 08:40:24 INFO - Running post-action listener: _resource_record_post_action 08:40:24 INFO - [mozharness: 2017-05-10 15:40:24.708382Z] Finished read-buildbot-config step (success) 08:40:24 INFO - [mozharness: 2017-05-10 15:40:24.708509Z] Running download-and-extract step. 08:40:24 INFO - Running pre-action listener: _resource_record_pre_action 08:40:24 INFO - Running main action method: download_and_extract 08:40:24 INFO - mkdir: /builds/slave/test/build/tests 08:40:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:24 INFO - trying https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 08:40:24 INFO - Downloading https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:40:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 08:40:25 INFO - Downloaded 1325 bytes. 08:40:25 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:40:25 INFO - Using the following test package requirements: 08:40:25 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:40:25 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 08:40:25 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:40:25 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:40:25 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 08:40:25 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:40:25 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 08:40:25 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:40:25 INFO - u'jsshell-mac.zip'], 08:40:25 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:40:25 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 08:40:25 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:40:25 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:40:25 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 08:40:25 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:40:25 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 08:40:25 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:40:25 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 08:40:25 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:40:25 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 08:40:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:40:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:40:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:40:25 INFO - Fetch https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:40:26 INFO - Content-Length response header: 52707691 08:40:26 INFO - Bytes received: 52707691 08:40:43 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:40:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:40:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:40:43 INFO - Fetch https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:40:44 INFO - Content-Length response header: 52707691 08:40:44 INFO - Bytes received: 52707691 08:41:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 08:41:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:41:00 INFO - Fetch https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 08:41:01 INFO - Content-Length response header: 14409362 08:41:01 INFO - Bytes received: 14409362 08:41:01 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')]} 08:41:01 INFO - trying https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:41:01 INFO - Downloading https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 08:41:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 08:41:03 INFO - Downloaded 75909304 bytes. 08:41:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:41:03 INFO - mkdir: /builds/slave/test/properties 08:41:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:41:03 INFO - Writing to file /builds/slave/test/properties/build_url 08:41:03 INFO - Contents: 08:41:03 INFO - build_url:https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:41:03 INFO - Running post-action listener: _resource_record_post_action 08:41:03 INFO - Running post-action listener: set_extra_try_arguments 08:41:03 INFO - [mozharness: 2017-05-10 15:41:03.979663Z] Finished download-and-extract step (success) 08:41:03 INFO - [mozharness: 2017-05-10 15:41:03.979786Z] Running populate-webroot step. 08:41:03 INFO - Running pre-action listener: _resource_record_pre_action 08:41:03 INFO - Running main action method: populate_webroot 08:41:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:41:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:41:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:41:03 INFO - '--tppagecycles', 08:41:03 INFO - '7'], 08:41:03 INFO - 'tests': ['tsvgm']}}, 08:41:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:41:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:41:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:41:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:41:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:41:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:41:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:41:03 INFO - 'talos_options': ['--disable-e10s'], 08:41:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:41:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:41:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:41:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:41:03 INFO - 'talos_options': ['--disable-e10s'], 08:41:03 INFO - 'tests': ['damp', 'tps']}, 08:41:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:41:03 INFO - 'tests': ['damp', 'tps']}, 08:41:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:41:03 INFO - 'tests': ['dromaeo_dom']}, 08:41:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:41:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:41:03 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:41:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:41:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:41:03 INFO - 'tests': ['a11yr', 08:41:03 INFO - 'ts_paint', 08:41:03 INFO - 'tpaint', 08:41:03 INFO - 'sessionrestore', 08:41:03 INFO - 'sessionrestore_no_auto_restore', 08:41:03 INFO - 'tabpaint']}, 08:41:03 INFO - 'other-e10s': {'tests': ['a11yr', 08:41:03 INFO - 'ts_paint', 08:41:03 INFO - 'tpaint', 08:41:03 INFO - 'sessionrestore', 08:41:03 INFO - 'sessionrestore_no_auto_restore', 08:41:03 INFO - 'tabpaint']}, 08:41:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:41:03 INFO - 'ts_paint', 08:41:03 INFO - 'tpaint', 08:41:03 INFO - 'sessionrestore', 08:41:03 INFO - 'sessionrestore_no_auto_restore', 08:41:03 INFO - 'tabpaint']}, 08:41:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:41:03 INFO - 'ts_paint', 08:41:03 INFO - 'tpaint', 08:41:03 INFO - 'sessionrestore', 08:41:03 INFO - 'sessionrestore_no_auto_restore', 08:41:03 INFO - 'tabpaint']}, 08:41:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:41:03 INFO - 'tests': ['a11yr', 08:41:03 INFO - 'ts_paint', 08:41:03 INFO - 'tpaint', 08:41:03 INFO - 'sessionrestore', 08:41:03 INFO - 'sessionrestore_no_auto_restore', 08:41:03 INFO - 'tabpaint']}, 08:41:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:41:03 INFO - 'tests': ['a11yr', 08:41:03 INFO - 'ts_paint', 08:41:03 INFO - 'tpaint', 08:41:03 INFO - 'sessionrestore', 08:41:03 INFO - 'sessionrestore_no_auto_restore', 08:41:03 INFO - 'tabpaint']}, 08:41:03 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:41:03 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:41:03 INFO - 'bloom_basic_ref']}, 08:41:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:41:03 INFO - 'tests': ['tsvgx', 08:41:03 INFO - 'tsvgr_opacity', 08:41:03 INFO - 'tart', 08:41:03 INFO - 'tscrollx', 08:41:03 INFO - 'cart', 08:41:03 INFO - 'tsvg_static']}, 08:41:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:41:03 INFO - 'tsvgr_opacity', 08:41:03 INFO - 'tart', 08:41:03 INFO - 'tscrollx', 08:41:03 INFO - 'cart', 08:41:03 INFO - 'tsvg_static']}, 08:41:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:41:03 INFO - 'talos_options': ['--disable-e10s'], 08:41:03 INFO - 'tests': ['tp5o']}, 08:41:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:41:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:41:03 INFO - 'talos_options': ['--disable-e10s', 08:41:03 INFO - '--xperf_path', 08:41:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:41:03 INFO - 'tests': ['tp5n']}, 08:41:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:41:03 INFO - 'talos_options': ['--xperf_path', 08:41:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:41:04 INFO - 'tests': ['tp5n']}}, 08:41:04 INFO - 'talos.zip': {'path': '', 08:41:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:41:04 INFO - Running post-action listener: _resource_record_post_action 08:41:04 INFO - [mozharness: 2017-05-10 15:41:04.000641Z] Finished populate-webroot step (success) 08:41:04 INFO - [mozharness: 2017-05-10 15:41:04.000758Z] Running create-virtualenv step. 08:41:04 INFO - Running pre-action listener: _resource_record_pre_action 08:41:04 INFO - Running main action method: create_virtualenv 08:41:04 INFO - Creating virtualenv /builds/slave/test/build/venv 08:41:04 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 08:41:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:41:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:41:04 INFO - Using real prefix '/tools/python27' 08:41:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:41:04 INFO - Installing distribute.............................................................................................................................................................................................done. 08:41:07 INFO - Installing pip.................done. 08:41:07 INFO - Return code: 0 08:41:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:41:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:41:08 INFO - Reading from file tmpfile_stdout 08:41:08 INFO - Output received: 08:41:08 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:41:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:41: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')]} 08:41:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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')]} 08:41:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1037edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88bd786d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034fed30>, '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 0x1034b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034f2be8>, '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.gY6bb4FO7U/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.wsZedlf1cF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 08:41:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:41:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:41:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gY6bb4FO7U/Render', 08:41:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:08 INFO - 'HOME': '/Users/cltbld', 08:41:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:08 INFO - 'LOGNAME': 'cltbld', 08:41:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:08 INFO - 'MOZ_NO_REMOTE': '1', 08:41:08 INFO - 'NO_EM_RESTART': '1', 08:41:08 INFO - 'PAGER': '/bin/cat', 08:41:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:08 INFO - 'PWD': '/builds/slave/test', 08:41:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:08 INFO - 'SHELL': '/bin/bash', 08:41:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wsZedlf1cF/Listeners', 08:41:08 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 08:41:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:08 INFO - 'USER': 'cltbld', 08:41:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:08 INFO - 'XPC_FLAGS': '0x0', 08:41:08 INFO - 'XPC_SERVICE_NAME': '0', 08:41:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:08 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 08:41:08 INFO - Cleaning up... 08:41:08 INFO - Return code: 0 08:41:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:41: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')]} 08:41:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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')]} 08:41:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1037edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88bd786d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034fed30>, '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 0x1034b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034f2be8>, '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.gY6bb4FO7U/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.wsZedlf1cF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 08:41:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:41:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:41:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gY6bb4FO7U/Render', 08:41:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:08 INFO - 'HOME': '/Users/cltbld', 08:41:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:08 INFO - 'LOGNAME': 'cltbld', 08:41:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:08 INFO - 'MOZ_NO_REMOTE': '1', 08:41:08 INFO - 'NO_EM_RESTART': '1', 08:41:08 INFO - 'PAGER': '/bin/cat', 08:41:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:08 INFO - 'PWD': '/builds/slave/test', 08:41:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:08 INFO - 'SHELL': '/bin/bash', 08:41:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wsZedlf1cF/Listeners', 08:41:08 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 08:41:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:08 INFO - 'USER': 'cltbld', 08:41:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:08 INFO - 'XPC_FLAGS': '0x0', 08:41:08 INFO - 'XPC_SERVICE_NAME': '0', 08:41:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:08 INFO - Downloading/unpacking psutil>=3.1.1 08:41:08 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 08:41:08 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 08:41:08 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 08:41:08 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 08:41:08 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 08:41:08 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 08:41:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:41:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:41:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:41:11 INFO - Installing collected packages: psutil 08:41:11 INFO - Running setup.py install for psutil 08:41:11 INFO - building 'psutil._psutil_osx' extension 08:41:11 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 08:41:11 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 08:41:11 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 08:41:12 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 08:41:12 INFO - building 'psutil._psutil_posix' extension 08:41:12 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 08:41:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:41:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:41:12 INFO - ^ 08:41:12 INFO - 1 warning generated. 08:41:12 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 08:41:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:41:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:41:12 INFO - Successfully installed psutil 08:41:12 INFO - Cleaning up... 08:41:12 INFO - Return code: 0 08:41:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:41:12 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')]} 08:41:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:12 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')]} 08:41:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:12 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 0x1037edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88bd786d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034fed30>, '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 0x1034b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034f2be8>, '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.gY6bb4FO7U/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.wsZedlf1cF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 08:41:12 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 08:41:12 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 08:41:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gY6bb4FO7U/Render', 08:41:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:12 INFO - 'HOME': '/Users/cltbld', 08:41:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:12 INFO - 'LOGNAME': 'cltbld', 08:41:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:12 INFO - 'MOZ_NO_REMOTE': '1', 08:41:12 INFO - 'NO_EM_RESTART': '1', 08:41:12 INFO - 'PAGER': '/bin/cat', 08:41:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:12 INFO - 'PWD': '/builds/slave/test', 08:41:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:12 INFO - 'SHELL': '/bin/bash', 08:41:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wsZedlf1cF/Listeners', 08:41:12 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 08:41:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:12 INFO - 'USER': 'cltbld', 08:41:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:12 INFO - 'XPC_FLAGS': '0x0', 08:41:12 INFO - 'XPC_SERVICE_NAME': '0', 08:41:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:41:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:41:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:41:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:41:14 INFO - Installing collected packages: mozsystemmonitor 08:41:14 INFO - Running setup.py install for mozsystemmonitor 08:41:14 INFO - Successfully installed mozsystemmonitor 08:41:14 INFO - Cleaning up... 08:41:14 INFO - Return code: 0 08:41:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:41:14 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')]} 08:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:14 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')]} 08:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:14 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 0x1037edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88bd786d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034fed30>, '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 0x1034b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034f2be8>, '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.gY6bb4FO7U/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.wsZedlf1cF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 08:41:14 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 08:41:14 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 08:41:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gY6bb4FO7U/Render', 08:41:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:14 INFO - 'HOME': '/Users/cltbld', 08:41:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:14 INFO - 'LOGNAME': 'cltbld', 08:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:14 INFO - 'MOZ_NO_REMOTE': '1', 08:41:14 INFO - 'NO_EM_RESTART': '1', 08:41:14 INFO - 'PAGER': '/bin/cat', 08:41:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:14 INFO - 'PWD': '/builds/slave/test', 08:41:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:14 INFO - 'SHELL': '/bin/bash', 08:41:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wsZedlf1cF/Listeners', 08:41:14 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 08:41:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:14 INFO - 'USER': 'cltbld', 08:41:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:14 INFO - 'XPC_FLAGS': '0x0', 08:41:14 INFO - 'XPC_SERVICE_NAME': '0', 08:41:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:14 INFO - Downloading/unpacking jsonschema==2.5.1 08:41:14 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 08:41:14 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 08:41:14 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 08:41:14 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 08:41:14 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 08:41:14 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 08:41:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:41:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:41:15 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 08:41:15 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 08:41:15 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 08:41:15 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 08:41:15 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 08:41:15 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 08:41:16 INFO - Downloading functools32-3.2.3-2.tar.gz 08:41:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:41:16 INFO - warning: no files found matching '*.txt' 08:41:16 INFO - no previously-included directories found matching 'build' 08:41:16 INFO - no previously-included directories found matching 'dist' 08:41:16 INFO - no previously-included directories found matching '.git*' 08:41:16 INFO - Installing collected packages: jsonschema, functools32 08:41:16 INFO - Running setup.py install for functools32 08:41:16 INFO - warning: no files found matching '*.txt' 08:41:16 INFO - no previously-included directories found matching 'build' 08:41:16 INFO - no previously-included directories found matching 'dist' 08:41:16 INFO - no previously-included directories found matching '.git*' 08:41:16 INFO - Successfully installed jsonschema functools32 08:41:16 INFO - Cleaning up... 08:41:16 INFO - Return code: 0 08:41:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:41:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:16 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 0x1037edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88bd786d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034fed30>, '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 0x1034b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034f2be8>, '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.gY6bb4FO7U/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.wsZedlf1cF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 08:41:16 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 08:41:16 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 08:41:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gY6bb4FO7U/Render', 08:41:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:16 INFO - 'HOME': '/Users/cltbld', 08:41:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:16 INFO - 'LOGNAME': 'cltbld', 08:41:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:16 INFO - 'MOZ_NO_REMOTE': '1', 08:41:16 INFO - 'NO_EM_RESTART': '1', 08:41:16 INFO - 'PAGER': '/bin/cat', 08:41:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:16 INFO - 'PWD': '/builds/slave/test', 08:41:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:16 INFO - 'SHELL': '/bin/bash', 08:41:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wsZedlf1cF/Listeners', 08:41:16 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 08:41:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:16 INFO - 'USER': 'cltbld', 08:41:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:16 INFO - 'XPC_FLAGS': '0x0', 08:41:16 INFO - 'XPC_SERVICE_NAME': '0', 08:41:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:41:16 INFO - Cleaning up... 08:41:16 INFO - Return code: 0 08:41:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:41:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:16 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 0x1037edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88bd786d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034fed30>, '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 0x1034b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034f2be8>, '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.gY6bb4FO7U/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.wsZedlf1cF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 08:41:16 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 08:41:16 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 08:41:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gY6bb4FO7U/Render', 08:41:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:16 INFO - 'HOME': '/Users/cltbld', 08:41:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:16 INFO - 'LOGNAME': 'cltbld', 08:41:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:16 INFO - 'MOZ_NO_REMOTE': '1', 08:41:16 INFO - 'NO_EM_RESTART': '1', 08:41:16 INFO - 'PAGER': '/bin/cat', 08:41:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:16 INFO - 'PWD': '/builds/slave/test', 08:41:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:16 INFO - 'SHELL': '/bin/bash', 08:41:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wsZedlf1cF/Listeners', 08:41:16 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 08:41:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:16 INFO - 'USER': 'cltbld', 08:41:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:16 INFO - 'XPC_FLAGS': '0x0', 08:41:16 INFO - 'XPC_SERVICE_NAME': '0', 08:41:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:17 INFO - Downloading/unpacking blobuploader==1.2.4 08:41:17 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 08:41:17 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 08:41:17 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 08:41:17 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 08:41:17 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 08:41:17 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 08:41:18 INFO - Downloading blobuploader-1.2.4.tar.gz 08:41:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:41:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:41:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:41:19 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 08:41:19 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 08:41:19 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 08:41:19 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 08:41:19 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 08:41:19 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 08:41:19 INFO - Downloading docopt-0.6.1.tar.gz 08:41:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:41:19 INFO - Installing collected packages: blobuploader, requests, docopt 08:41:19 INFO - Running setup.py install for blobuploader 08:41:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:41:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:41:19 INFO - Running setup.py install for requests 08:41:20 INFO - Running setup.py install for docopt 08:41:20 INFO - Successfully installed blobuploader requests docopt 08:41:20 INFO - Cleaning up... 08:41:20 INFO - Return code: 0 08:41:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:41:20 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')]} 08:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:20 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')]} 08:41:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:20 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 0x1037edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88bd786d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034fed30>, '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 0x1034b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034f2be8>, '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.gY6bb4FO7U/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.wsZedlf1cF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 08:41:20 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 08:41:20 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 08:41:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gY6bb4FO7U/Render', 08:41:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:20 INFO - 'HOME': '/Users/cltbld', 08:41:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:20 INFO - 'LOGNAME': 'cltbld', 08:41:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:20 INFO - 'MOZ_NO_REMOTE': '1', 08:41:20 INFO - 'NO_EM_RESTART': '1', 08:41:20 INFO - 'PAGER': '/bin/cat', 08:41:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:20 INFO - 'PWD': '/builds/slave/test', 08:41:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:20 INFO - 'SHELL': '/bin/bash', 08:41:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wsZedlf1cF/Listeners', 08:41:20 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 08:41:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:20 INFO - 'USER': 'cltbld', 08:41:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:20 INFO - 'XPC_FLAGS': '0x0', 08:41:20 INFO - 'XPC_SERVICE_NAME': '0', 08:41:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:41:20 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-02zwX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:41:20 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-0w8F_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:41:20 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-yvGg2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:41:20 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-BYZwvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:41:20 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-qkvvAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:41:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-fPpwcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:41:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-G4bLHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:41:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-OZg16y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:41:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-AgtEvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:41:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Urjo9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:41:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-RnNghR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:41:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-glbp6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:41:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-dXQmfU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:41:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-XDop4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:22 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-NNXKgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:41:22 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-yRsCEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:41:22 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-H4pjxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:41:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:41:22 INFO - Running setup.py install for manifestparser 08:41:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:41:22 INFO - Running setup.py install for mozcrash 08:41:22 INFO - Running setup.py install for mozdebug 08:41:22 INFO - Running setup.py install for mozdevice 08:41:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:41:22 INFO - Running setup.py install for mozfile 08:41:23 INFO - Running setup.py install for mozhttpd 08:41:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:41:23 INFO - Running setup.py install for mozinfo 08:41:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:41:23 INFO - Running setup.py install for mozInstall 08:41:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:41:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:41:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:41:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:41:23 INFO - Running setup.py install for mozleak 08:41:23 INFO - Running setup.py install for mozlog 08:41:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:41:23 INFO - Running setup.py install for moznetwork 08:41:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:41:23 INFO - Running setup.py install for mozprocess 08:41:24 INFO - Running setup.py install for mozprofile 08:41:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:41:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:41:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:41:24 INFO - Running setup.py install for mozrunner 08:41:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:41:24 INFO - Running setup.py install for mozscreenshot 08:41:24 INFO - Running setup.py install for moztest 08:41:24 INFO - Running setup.py install for mozversion 08:41:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:41:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:41:24 INFO - Cleaning up... 08:41:24 INFO - Return code: 0 08:41:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:41:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:24 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 0x1037edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88bd786d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034fed30>, '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 0x1034b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034f2be8>, '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.gY6bb4FO7U/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.wsZedlf1cF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 08:41:24 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 08:41:24 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 08:41:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gY6bb4FO7U/Render', 08:41:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:24 INFO - 'HOME': '/Users/cltbld', 08:41:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:24 INFO - 'LOGNAME': 'cltbld', 08:41:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:24 INFO - 'MOZ_NO_REMOTE': '1', 08:41:24 INFO - 'NO_EM_RESTART': '1', 08:41:24 INFO - 'PAGER': '/bin/cat', 08:41:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:24 INFO - 'PWD': '/builds/slave/test', 08:41:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:24 INFO - 'SHELL': '/bin/bash', 08:41:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wsZedlf1cF/Listeners', 08:41:24 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 08:41:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:24 INFO - 'USER': 'cltbld', 08:41:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:24 INFO - 'XPC_FLAGS': '0x0', 08:41:24 INFO - 'XPC_SERVICE_NAME': '0', 08:41:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:41:25 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-yoykR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:41:25 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)) 08:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:41:25 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Xa672Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:41:25 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-iUM24M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:41:25 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)) 08:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:41:25 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-6NqXFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 08:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:41:25 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Ryxxvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:41:25 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)) 08:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:41:25 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Lq3nkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:41:25 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)) 08:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:41:25 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-YxQBJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:41:25 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)) 08:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:41:25 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-ADjnj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:41:25 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)) 08:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:41:25 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-rWbXXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:41:26 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)) 08:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:41:26 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-FBkKUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:41:26 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)) 08:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:41:26 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-URlszK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:41:26 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)) 08:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:41:26 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-ucVD7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:41:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:41:26 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-CVCOk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:41:26 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)) 08:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:41:26 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-bLuthn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:41:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:26 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Bin0gC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:26 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)) 08:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:41:26 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-D0tAxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:41:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:41:26 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-ogJgMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:41:26 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)) 08:41:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:41:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:41:26 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)) 08:41:26 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:41:26 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:41:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:41:26 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 08:41:26 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 08:41:26 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 08:41:26 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 08:41:26 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 08:41:26 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 08:41:28 INFO - Downloading blessings-1.6.tar.gz 08:41:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:41:28 INFO - Installing collected packages: blessings 08:41:28 INFO - Running setup.py install for blessings 08:41:28 INFO - Successfully installed blessings 08:41:28 INFO - Cleaning up... 08:41:28 INFO - Return code: 0 08:41:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:41:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:41:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:41:28 INFO - Reading from file tmpfile_stdout 08:41:28 INFO - Current package versions: 08:41:28 INFO - blessings == 1.6 08:41:28 INFO - blobuploader == 1.2.4 08:41:28 INFO - docopt == 0.6.1 08:41:28 INFO - functools32 == 3.2.3-2 08:41:28 INFO - jsonschema == 2.5.1 08:41:28 INFO - manifestparser == 1.1 08:41:28 INFO - mozInstall == 1.12 08:41:28 INFO - mozcrash == 1.0 08:41:28 INFO - mozdebug == 0.1 08:41:28 INFO - mozdevice == 0.50 08:41:28 INFO - mozfile == 1.2 08:41:28 INFO - mozhttpd == 0.7 08:41:28 INFO - mozinfo == 0.9 08:41:28 INFO - mozleak == 0.1 08:41:28 INFO - mozlog == 3.4 08:41:28 INFO - moznetwork == 0.27 08:41:28 INFO - mozprocess == 0.25 08:41:28 INFO - mozprofile == 0.28 08:41:28 INFO - mozrunner == 6.13 08:41:28 INFO - mozscreenshot == 0.1 08:41:28 INFO - mozsystemmonitor == 0.3 08:41:28 INFO - moztest == 0.8 08:41:28 INFO - mozversion == 1.4 08:41:28 INFO - psutil == 3.1.1 08:41:28 INFO - requests == 1.2.3 08:41:28 INFO - wsgiref == 0.1.2 08:41:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:28 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 0x1037edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88bd786d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034fed30>, '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 0x1034b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034f2be8>, '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.gY6bb4FO7U/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.wsZedlf1cF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 08:41:28 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 08:41:28 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 08:41:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gY6bb4FO7U/Render', 08:41:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:28 INFO - 'HOME': '/Users/cltbld', 08:41:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:28 INFO - 'LOGNAME': 'cltbld', 08:41:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:28 INFO - 'MOZ_NO_REMOTE': '1', 08:41:28 INFO - 'NO_EM_RESTART': '1', 08:41:28 INFO - 'PAGER': '/bin/cat', 08:41:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:28 INFO - 'PWD': '/builds/slave/test', 08:41:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:28 INFO - 'SHELL': '/bin/bash', 08:41:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wsZedlf1cF/Listeners', 08:41:28 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 08:41:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:28 INFO - 'USER': 'cltbld', 08:41:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:28 INFO - 'XPC_FLAGS': '0x0', 08:41:28 INFO - 'XPC_SERVICE_NAME': '0', 08:41:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:29 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)) 08:41:29 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)) 08:41:29 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)) 08:41:29 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)) 08:41:29 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)) 08:41:29 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)) 08:41:29 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)) 08:41:29 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)) 08:41:29 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)) 08:41:29 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)) 08:41:29 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)) 08:41:29 INFO - Cleaning up... 08:41:29 INFO - Return code: 0 08:41:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:41:29 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')]} 08:41:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:29 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')]} 08:41:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:29 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 0x1037edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd88bd786d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034fed30>, '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 0x1034b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034f2be8>, '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.gY6bb4FO7U/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.wsZedlf1cF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 08:41:29 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 08:41:29 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 08:41:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gY6bb4FO7U/Render', 08:41:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:29 INFO - 'HOME': '/Users/cltbld', 08:41:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:29 INFO - 'LOGNAME': 'cltbld', 08:41:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:29 INFO - 'MOZ_NO_REMOTE': '1', 08:41:29 INFO - 'NO_EM_RESTART': '1', 08:41:29 INFO - 'PAGER': '/bin/cat', 08:41:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:29 INFO - 'PWD': '/builds/slave/test', 08:41:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:29 INFO - 'SHELL': '/bin/bash', 08:41:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wsZedlf1cF/Listeners', 08:41:29 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 08:41:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:29 INFO - 'USER': 'cltbld', 08:41:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:29 INFO - 'XPC_FLAGS': '0x0', 08:41:29 INFO - 'XPC_SERVICE_NAME': '0', 08:41:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:41:29 INFO - Cleaning up... 08:41:29 INFO - Return code: 0 08:41:29 INFO - Running post-action listener: _resource_record_post_action 08:41:29 INFO - Running post-action listener: _start_resource_monitoring 08:41:29 INFO - Starting resource monitoring. 08:41:29 INFO - [mozharness: 2017-05-10 15:41:29.514334Z] Finished create-virtualenv step (success) 08:41:29 INFO - [mozharness: 2017-05-10 15:41:29.515321Z] Running install step. 08:41:29 INFO - Running pre-action listener: _resource_record_pre_action 08:41:29 INFO - Running main action method: install 08:41:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:41:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:41:29 INFO - Reading from file tmpfile_stdout 08:41:29 INFO - Detecting whether we're running mozinstall >=1.0... 08:41:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:41:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:41:29 INFO - Reading from file tmpfile_stdout 08:41:29 INFO - Output received: 08:41:29 INFO - Usage: mozinstall [options] installer 08:41:29 INFO - Options: 08:41:29 INFO - -h, --help show this help message and exit 08:41:29 INFO - -d DEST, --destination=DEST 08:41:29 INFO - Directory to install application into. [default: 08:41:29 INFO - "/builds/slave/test"] 08:41:29 INFO - --app=APP Application being installed. [default: firefox] 08:41:29 INFO - mkdir: /builds/slave/test/build/application 08:41:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:41:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:41:50 INFO - Reading from file tmpfile_stdout 08:41:50 INFO - Output received: 08:41:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:41:50 INFO - Running post-action listener: _resource_record_post_action 08:41:50 INFO - [mozharness: 2017-05-10 15:41:50.083803Z] Finished install step (success) 08:41:50 INFO - [mozharness: 2017-05-10 15:41:50.083964Z] Running run-tests step. 08:41:50 INFO - Running pre-action listener: _resource_record_pre_action 08:41:50 INFO - Running main action method: run_tests 08:41:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:41:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:41:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:41:50 INFO - 2017-05-10 08:41:50.098 screenresolution[1694:8024] starting screenresolution argv=screenresolution get 08:41:50 INFO - 2017-05-10 08:41:50.101 screenresolution[1694:8024] Display 0: 1600x1200x32@60 08:41:50 INFO - 2017-05-10 08:41:50.107 screenresolution[1695:8026] starting screenresolution argv=screenresolution list 08:41:50 INFO - Available Modes on Display 0 08:41:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:41:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:41:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:41:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:41:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:41:50 INFO - Intel Iris: 08:41:50 INFO - Chipset Model: Intel Iris 08:41:50 INFO - Type: GPU 08:41:50 INFO - Bus: Built-In 08:41:50 INFO - VRAM (Dynamic, Max): 1536 MB 08:41:50 INFO - Vendor: Intel (0x8086) 08:41:50 INFO - Device ID: 0x0a2e 08:41:50 INFO - Revision ID: 0x0009 08:41:50 INFO - Displays: 08:41:50 INFO - Display: 08:41:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:41:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:41:50 INFO - Display Serial Number: 1600x1200 60 08:41:50 INFO - Main Display: Yes 08:41:50 INFO - Mirror: Off 08:41:50 INFO - Online: Yes 08:41:50 INFO - Rotation: Supported 08:41:50 INFO - Return code: 0 08:41:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:41:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:41:50 INFO - Python 2.7.3 08:41:50 INFO - Return code: 0 08:41:50 INFO - grabbing minidump binary from tooltool 08:41: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')]} 08:41:50 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 0x1034fed30>, '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 0x1034b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034f2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:41:50 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 08:41:50 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 08:41:50 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 08:41:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:41:50 INFO - Return code: 0 08:41:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:41:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:41:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:41:50 INFO - ENV: RUST_BACKTRACE is now 1 08:41:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:41:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:41:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0035', '--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 08:41:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0035 --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 08:41:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gY6bb4FO7U/Render', 08:41:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:50 INFO - 'HOME': '/Users/cltbld', 08:41:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:50 INFO - 'LOGNAME': 'cltbld', 08:41:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:41:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:41:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:50 INFO - 'MOZ_NO_REMOTE': '1', 08:41:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:41:50 INFO - 'NO_EM_RESTART': '1', 08:41:50 INFO - 'PAGER': '/bin/cat', 08:41:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:50 INFO - 'PWD': '/builds/slave/test', 08:41:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:41:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:50 INFO - 'RUST_BACKTRACE': '1', 08:41:50 INFO - 'SHELL': '/bin/bash', 08:41:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wsZedlf1cF/Listeners', 08:41:50 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 08:41:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:50 INFO - 'USER': 'cltbld', 08:41:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:50 INFO - 'XPC_FLAGS': '0x0', 08:41:50 INFO - 'XPC_SERVICE_NAME': '0', 08:41:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0035', '--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 08:41:50 INFO - mozversion application_buildid: 20170510061209 08:41:50 INFO - mozversion application_changeset: ebbcdaa5b5802ecd39624dd2acbdda8547b8384d 08:41:50 INFO - mozversion application_display_name: Nightly 08:41:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:41:50 INFO - mozversion application_name: Firefox 08:41:50 INFO - mozversion application_remotingname: firefox 08:41:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:41:50 INFO - mozversion application_vendor: Mozilla 08:41:50 INFO - mozversion application_version: 55.0a1 08:41:50 INFO - mozversion platform_buildid: 20170510061209 08:41:50 INFO - mozversion platform_changeset: ebbcdaa5b5802ecd39624dd2acbdda8547b8384d 08:41:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:41:50 INFO - mozversion platform_version: 55.0a1 08:41:50 INFO - using testdate: 1494430910 08:41:50 INFO - actual date: 1494430910 08:41:50 INFO - starting webserver on 'localhost:49243' 08:41:50 INFO - SUITE-START | Running 2 tests 08:41:50 INFO - TEST-START | dromaeo_css 08:41:50 INFO - operating with platform_type : mac_ 08:41:50 INFO - Initialising browser for dromaeo_css test... 08:41:50 INFO - TEST-INFO | started process 1705 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmp92EOs7/profile http://localhost:49243/getInfo.html) 08:41:53 INFO - PID 1705 | 2017-05-10 08:41:53.161 plugin-container[1708:8213] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 08:41:53 INFO - PID 1705 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:53 INFO - PID 1705 | 2017-05-10 08:41:53.164 plugin-container[1708:8213] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491b, name = 'com.apple.CFPasteboardClient' 08:41:53 INFO - PID 1705 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:53 INFO - PID 1705 | 2017-05-10 08:41:53.164 plugin-container[1708:8213] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:41:53 INFO - PID 1705 | __metrics Screen width/height:1600/1200 08:41:53 INFO - PID 1705 | colorDepth:24 08:41:53 INFO - PID 1705 | Browser inner width/height: 1024/691 08:41:53 INFO - PID 1705 | __metrics 08:41:54 INFO - TEST-INFO | 1705: exit 0 08:41:54 INFO - Browser initialized. 08:41:54 INFO - Running cycle 1/1 for dromaeo_css test... 08:41:54 INFO - TEST-INFO | started process 1711 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmp92EOs7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:41:56 INFO - PID 1711 | 2017-05-10 08:41:56.375 plugin-container[1712:8381] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 08:41:56 INFO - PID 1711 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:56 INFO - PID 1711 | 2017-05-10 08:41:56.376 plugin-container[1712:8381] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a23, name = 'com.apple.CFPasteboardClient' 08:41:56 INFO - PID 1711 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:56 INFO - PID 1711 | 2017-05-10 08:41:56.376 plugin-container[1712:8381] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:41:56 INFO - PID 1711 | 2017-05-10 08:41:56.896 plugin-container[1713:8424] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 08:41:56 INFO - PID 1711 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:56 INFO - PID 1711 | 2017-05-10 08:41:56.897 plugin-container[1713:8424] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4613, name = 'com.apple.CFPasteboardClient' 08:41:56 INFO - PID 1711 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:56 INFO - PID 1711 | 2017-05-10 08:41:56.897 plugin-container[1713:8424] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:42:06 INFO - PID 1711 | RSS: Main: 228573184 08:42:06 INFO - PID 1711 | 08:45:09 INFO - PID 1711 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 08:45:11 INFO - PID 1711 | RSS: Main: 257667072 08:45:11 INFO - PID 1711 | 08:48:13 INFO - PID 1711 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 08:48:13 INFO - PID 1711 | RSS: Main: 262365184 08:48:13 INFO - PID 1711 | 08:51:15 INFO - PID 1711 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 08:51:16 INFO - PID 1711 | RSS: Main: 271196160 08:51:16 INFO - PID 1711 | 08:54:17 INFO - PID 1711 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 08:54:18 INFO - PID 1711 | RSS: Main: 264032256 08:54:18 INFO - PID 1711 | 08:57:21 INFO - PID 1711 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 08:57:21 INFO - PID 1711 | RSS: Main: 267567104 08:57:21 INFO - PID 1711 | 09:00:23 INFO - PID 1711 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 09:00:23 INFO - PID 1711 | RSS: Main: 266305536 09:00:23 INFO - PID 1711 | 09:00:23 INFO - PID 1711 | __start_tp_report 09:00:23 INFO - PID 1711 | _x_x_mozilla_page_load 09:00:23 INFO - PID 1711 | _x_x_mozilla_page_load_details 09:00:23 INFO - PID 1711 | |i|pagename|runs| 09:00:23 INFO - PID 1711 | |0;dojo.html;3565.8395372208265;3603.981980090099;3610.5667319921613;3625.981870090649;3630.0561853918007;6139.171211886394;6140.127515739758;6194.814155575335;6202.0076787713915;6212.968935155318;6139.15484235078;6156.015037593986;6172.5061995040405;6203.751849926009;6240.219972503437;5365.839024829242;5389.5688344932505;5408.215808707735;5423.159410291411;5429.267048948391;5426.375966763813;5505.724713764316;5532.889342213153;5580.581456390787;5594.860128496779;5324.148136298174;5456.7544460498975;5490.149026900836;5527.695976721278;5539.639923404986;4170.249355116078;4450.688451808374;5384.40771515133;5384.407715151369;5456.208849716769;4023.7407699763285;5398.352197736297;5413.132469298086;5454.018276710191;5483.4516548344845;4921.311016457699;5047.621428392885;6092.66490343031;6146.416090471443;6217.253929528486;6442;6697.464202863759;6711.765088221889;6715.9328406715795;6726.966365168143;6660.966695166541;6712.899306510406;6759.966200169017;6777.457803375767;6794.898076528856;6236.282827474854;6238.844028899314;6306.369363063703;6308.589941653778;6313.053042043684;6979.267176946449;6985.406240469617;6990.26602206761;7027.121609798775;7034.824129396704;5670.914936275959;6255.186825712628;6487;6535.019747037983;6584.209894812653;6106.908396374058;6283.77466394058;6793.422559082534;6807.761728339485;6811.352921472452;6538.051982462516;6666.333366663392;6676.96661516699;6689.765858195037;6692.230393504712;6416.647084410402;6546.574472659262;6612.437942774918;6696.196456425163;6707.765228216989;5321.068812957717;5366.6243362965;5374.758135883894;5380.569554435636;5399.163129714901;4685.320628508915;4702.976485117552;4736.100140973273;4744.027474367816;4793.18515852299;6243.157173781481;6314.55798094129;6358.841028974312;6455.644939528371;6465.806025819233;6702.463802895757;6748.6963086660235;6770;6827.146606674166;6853.862922741518;6372.490200783926;6391.840203994937;6417.871642567123;6421.101045853584;6424.614523128627;5824.271966004249;5928.703564821828;5979.850503737442;6004.159417681528;6019.247594050744;6820.522563420612;6950.54821436605;6951.826204344932;6974.860502790018;7021.297870212938;5602.299712535933;6775.728970841111;6787.389134977876;6824.658767061528;6842.55523390978;6717.429018533284;6819.863602727818;6831.89752153728;6834.487413443912;7020.964895175492;6671.966640166962;6672.432843208382;6681.331866813474;6684.598924064571;6689.063531105747;4930.728809915489;6517.902231466437;6599.571027883172;6615.073889655549;6864.0047193155715;6534.509911756732;6556.868862622816;6567.9014814776865;6574.572652777554;6580.078788969644;6533.542652014314;6543.934560654333;6544.574602650812;6549.934500654933;6552.475801935739;5289.68261904287;5359.222912677562;5663.235463212413;5920.230370051866;6410.294867564658;5435.755391007335;6287.654179020198;6350.015747559136;6460.676966151767;6600.537962342782;6385.968070159805;6407.167068281094;6424.357564243538;6429.7428102875365;6456.354364563506 09:00:23 INFO - PID 1711 | |1;ext.html;749.9163708441305;813.2396209544075;826.8470332988397;1146.3752255021013;1269.2384569258452;37110.25779484412;37742.433863492;38099.23801523968;38231;38297.23405531888;37872.24255514895;38127.23745525088;38185;38241.42637860427;38326.80836595813;7256.6371681415985;7836.8040798980055;7934.880976785324;8347.207015333533;8532.14678532148;8477.66089356425;8551.401401901869;8590.570471476402;8606;8625.65497380104;8558.78603034926;8584.141585841428;8614.956925215396;8635.481871087723;8643.178898004711;8539.231469167742;8558.743237702847;8578.742637720878;8609.913900861005;8610.311175105977;8354.248117669378;8415.747527574182;8441.620127094295;8470.872936905951;8493.447925884797;37129.628703712886;37296.81351593252;37389.43915841264;37443.06392340214;37455.43816842722;3555.7510974231814;3600.531930848973;3600.6399360064047;3601.531800865897;3610.4584312353095;30389.088327350215;30401;30404.391912161635;30456.238594035327;30580.318082505455;2686;2721.5690567051465;2753.366725653091;2770.279727270924;2778.055461143201;288488;290534;304908;312006.439967801;312123.4393828039;39827.60172398297;43767.78116109399;46924.296135558;47016.529834701214;47290;49040.75479622579;49139.50860491349;49161.50838491641;49223.75388123036;49243.753781230866;11936.164468487123;13913.58259252224;13921.30393480322;14058.929705351613;14071.648208794657;9665.806683866424;11384;11404.429778511041;11415.086793056536;11551.653450396501;13251.403686834115;13382.330883455788;13416.12795168311;13422.731545369234;13497.460101595827;5201.829588342593;6024.638521688625;6026.005709058044;6027.78902738402;6086.908696369558;975.6390135649713;999.5052449037773;1040.3549799124592;1043.639944219243;1071.1430855315873;22612.886935565217;23489.412764680677;23714.7628523716;23747.406314841923;23919.402014949417;1150.6605551362368;1177.967533823075;1183.7632473505334;1187.281694574787;1197.203859433467;10165.491725413847;11723.94223158151;12125.939370303093;12142.089343299287;12264.202826816407;294821;308016.9198307988;330853;330997;332601;52270;52480;52556.47443525516;52566.73716631392;52570;1516.1282262698992;1542.9691406171814;1575.7557578575338;1576.054367379563;1579.889407741447;23166.652500212174;23213.187538436072;23222.535549289256;23488.412789680395;23511.76488235096;36760.81619591885;37135;37138.81430592938;37221.6277837229;37305.81347093247;267527;268154;268432;270398.6480067587;270526;32930;33280;34451.655483445844;36103.27793444064;36274.27451451009;40252.99367515835;43071.707848764585;43320;44829.77585112053;44998.550014500746;27786.027489037788;28843.711562884102;29487.705122948497;30683;30786.538201927437;25678.74321256838;29287.85356073206;29568.55647165251;30420.39159216759;30575.694243057285;35932;37424;37440.812795935846;37486;37943.62056379511;30132.397352052398;30158.698413016467;30282.54576181403;30981;31230.37539249248;20712.48218794542;22374.440638983506;22485.66271505896;22539;22597 09:00:23 INFO - PID 1711 | |2;jquery.html;383.79275191396647;426.2519278665941;426.4094229492154;436.6681322195131;452.7233202369318;19076.427707168772;20796.272130475416;21544.353669389904;21579.352619421403;21598.89200553997;21777.3466795996;22432.990515426805;22576.774232257674;23373.532529349402;23546.293611191693;6073.3622969588105;6300.054991751228;6301.275353334357;6315.3684631536935;6318.747250110013;5674.148877668362;6186.876262474748;6248.531360147985;6255.843603909893;6256.374362563753;5267.025600263944;6200.410960958701;6201.131841542165;6202.255729312466;6211.627302361872;5884.352721200665;6137.5089992800695;6161.9691901540655;6194.65929373882;6201.193844800192;5271.76277067529;5470.890582188374;5472.5621950243885;5476.3702174250075;5509.944900551023;27619.723802762117;27621;27794;27794.444111117868;28298.717012829813;2119.894005299744;2197.0662468450846;2203.3610253026604;2206.6910632511463;2208.2602328220064;17837.197326120226;17932;18080.276788928426;18291.45125646233;18498.53753656156;2145.7103291055664;2152.698622192894;2201.0535469748;2201.4716468047714;2209.1605190027685;139866.50333741738;146429;158619;160465;162054.18972905178;12333.136680432284;12818.166819156724;12932.288724120075;12935.223886566837;12959.935200324127;16587.004779713257;16596.336146554244;16671.99968001924;16706.58233544171;16713.247903844356;8838.646454141763;8975.685850995184;9090.86363704545;9134.680286190085;9207.723768286962;8524.522497997583;8600.827983440297;8610.741677749678;8731.694390696422;8763.780905477286;6883.724651014004;8107.55408452529;8176.877346839802;8228.094909559944;8238.134995825472;2258.2999270226283;2262.8868556572106;2271.6478945763433;2274.1812947338944;2444.9755502445105;942.3686130292721;954.1508057828538;992.9106380425796;1031.7626945802508;1037.8183817832;19960.401187964384;20027.399178024683;20872;20920.79079209219;20930.89534552348;942.0546571330182;969.966051188205;991.3803872579638;1005;1019.9592016319264;10414;10723.195760318007;10745.871683473122;10807.459627018774;10822.72061872668;441699.37450938264;464963;482302;504065;516817.41591291805;123516;125213;125412;137713.6228637721;141427.29286353503;1070.9678709638724;1079.3254216114929;1206.9215500992407;1219.481720268882;1219.4939100273286;18418.447446576625;20680.896595516926;21535.676964845854;22133.33599992003;22137.893105344345;54846.451535484135;58407.70796145992;59016.1147582778;59175.70412147912;59986.70006649939;82645.17354826375;82806;83079;84036.57981710053;84602.57698711714;8916.19754222123;8929.464232145832;8958.283337333129;9018.23345015681;9044.683436079706;13951.651208719863;14007.859921401063;14025.649358766112;14372.568822935329;14474.34865431037;10388.896111039096;11743.588974385855;11859;12110.576129835414;12289.262644241375;10119.190464762949;11417.257878237888;11448.656540303804;11549.307041577533;11714;9666.323357364918;9758.210679360342;12323.568675096329;12330.63008109758;12432.75134497323;8526.573671316286;8713.651453941771;8813.25087367581;9020.909790902007;9082.318826087938;11853.644390668294;12037.337946413032;12037.578684745991;12055.15613907018;12177.756444871231 09:00:23 INFO - PID 1711 | |3;mootools.html;1355.1801160298019;1451.912885226886;1469.5517867050553;1476.4315738440698;1486.4425840309884;11255;11612.477438515267;11763.35301558414;11771.352575608349;11883.64349069527;11499.655010349681;11674.766504669902;11684;11722.53109875606;11734.765304693901;2462.0644155221034;2482;2509.899604015837;2512.736162702917;2513.49730053989;2415.287490190398;2450.840695354805;2476.207613563651;2484.1181380609896;2489.004398240709;2469.7283298837115;2496.5381404440236;2527.988804478205;2530.493901219754;2531.6076008218756;2179.4442417183677;2459.1270099114827;2460.0774709483944;2511.2717311979504;2520.2943175910773;2387.558301714171;2393;2393.5811233034324;2400.651905473714;2505.5114252720728;11835.704107397298;11895.52417903283;11901.523939042429;11920.403979801062;11955.760884782343;6617.8014659560295;6774.322567743236;6867.793966181022;6872.037914691947;6880.380765731058;3712.573054098786;4022.7988600569856;4065.715399922007;4103.322951712964;4107.342825147962;1115.1636272795404;1129.2885482146278;1143.7712457508449;1182.875798041202;1189.3458597771191;16059.437919672755;16442.917785410995;16509.257083431276;16588.917055414644;16697.580705640034;5522.723863806874;5551.167324901297;5637.069883469264;5746.166805813217;5824.563157763185;11843.703907402383;11851.229670071418;11903.988161006238;11924.701882452835;11969.281843089442;6534.150560427114;6576.0793488911595;6579.83550411234;6596.142501474874;6615.338466153346;5962.701864906824;6123.203983482208;6157.353477884847;6198.845028874314;6215.533834962396;4271.5514870938105;4367.082912588392;4404.493483249404;4405.471343438744;4418.116376724668;1103.7902798468265;1263.8736126387287;1274.7577960187534;1276.5746075898992;1293.8641442648575;710.9502334836512;726.3281464645182;804.6057431858347;828.4490813608883;840.3949156607233;5133.435322114565;5948.018576934844;6261.78083767066;6268.279147897959;6449.774257900951;585.9414058594107;600.6996501749126;604.9637021778707;617.7405489694339;621.8911690454153;3445.6554344565343;3854.094287842355;3928.489296391508;3940.271049855786;4110.362893751473;14452.277386130652;14528.491502797562;15033.248337583078;15072.773908391382;15117.924410377878;14842.628934276729;14979.026363286352;15065.246737663292;15072.095674259579;15102.773458398133;7302.671379787922;8355.456895301786;8523.74428767138;8552.187542183483;8582;5322.600804939568;6737.494687898526;6986.615736134561;7018.087648605649;7065.929340706527;12463.629000810084;12634.684132896384;12762.87237127654;12818.871811281768;12858;11356.716082098013;13301.26843023643;13395;13514.729705406033;13789.310534473438;8356.913601231801;9009.81980360376;9343.81312373735;9467.621295148392;9506.714798556055;10559;10655.30740501865;10691.25161238737;10776.461176940964;10789.136869050613;7325.523840950321;9044.773880653036;9186.127317905055;9202.677906273248;9264.490453025148;8986.281097512314;9008.59461324255;9035.819283614423;9054.320925930713;9101.681441149793;8234.135415781257;8241;8348.998120225611;8367.456115352483;8367.497950123012;6095.969520152371;6153.4769544587425;6209.565330427007;6236.688165591793;6249.843753906189;5705.686187259741;6120.3267640558615;6300.2754683212015;6311.715972781328;6411.230652321751 09:00:23 INFO - PID 1711 | |4;prototype.html;397.38802244543405;402.4265421773972;404.6055096281126;404.67625899280586;409.2714967358104;6190.938090619093;6353.650549219791;6412.711427985741;6462.450691691212;6514.315996820336;5305;5475.972620136904;5685.516731077863;6138.416850399205;6384.585001974869;4707.83522576709;4774.450938142106;4786.904261914759;4797.208460603996;4800.399950006249;4636.976815115937;4781.187198176319;4793.616510679155;4798.5441383068555;4826.806927722904;4220.514640816315;4306.8277268909205;4728.314394412808;4730.90538189236;4754.8365493700285;4151.712968979626;4455.866324010285;4746.667733258673;4757.147709336475;4781.665283430161;3880.553736320331;4014.7992600369867;4167.541570427251;4226.344916537942;4237.851675191354;5872.265966754156;5903.321118071433;6144.815655530341;6147.313734795296;6179.87640247197;5444.549952521342;5453.836384908459;5456.645318054353;5476.452354764531;5502.477264659851;5657.405972372924;5682.374938756733;6583.736650533973;6805.115335006467;6915.75794847183;3653;4417.226985277595;4417.983863711333;4450.2434586120435;4462.709923854939;5803.709814509258;6842.076319696827;6910.723571057151;7216.201226184581;7266.45501587383;3526.7531272810656;3770.434434834797;4179.310413781692;4413.7351758894565;4535.7958891849285;3924.372100463912;4589.714879833652;4599.379083823708;4617.607503362185;4664.323673067454;5494.203340515603;5501.119820828648;5508.807191748269;5525.784327447955;5546.750396232099;6152.323244443108;6622.569532980341;6808.557443766139;6830.077939478107;6852.28051054642;1371.8971077169251;1456.8907331950147;1551.2166355990378;1568.5764843492193;1569.723900344505;979.6627603321557;1018.8318927377048;1032.4940779018227;1033.560736686905;1034.4930983818026;6556.213254409501;6556.934430655632;6556.95088785792;6573.704183311763;6601.966990165114;5720.942790572041;6461.709223084972;7079.079719636415;7085.96457017722;7097.538659987084;6063.272407311151;6296.740651869644;6495.447886929664;6513.990331498625;6535.444487218545;3647.6899463545365;3671.30245253401;3683.9263214735556;3702.018964974284;3716.5168528091176;7076.964615176891;7288.052553168161;7369.6315184240575;7380;7480.466504366701;6426.678666066678;6958.55282775068;7323.231060738546;7373.225811289844;7428.591427471433;5796.710164491843;7292.468581597924;7387.556746595222;7387.889181662279;7391.482596218429;5938.524918006463;6872.62200578973;7053.717851285891;7130.643467826484;7136.3934065602925;5783.975608536978;6069.969650151897;6085.543584231112;6610.471162306908;6973.302669733189;7285.380742636723;7352.70589176427;7356.264373562601;7377.741779037708;7397;4564.927242098171;4797.736124513046;5195.077716324151;5198.610104242121;5214.895702085862;5951.345352011362;6873.656317184221;7144.3570078693165;7146.964265178641;7150.42796576283;5652.558597557597;6584.736610535525;6771.966140169267;6865.725370985105;6906.171259448898;4909.410870695539;6674.798536263325;6855.794326170223;6855.897161542481;6866.279040700854;6386.201724784402;6664.566803157779;6711.496637752286;6726.293739157318;6745.392914637706;6865.347791959756;6875;6885.690143943635;6894.276101009523;6897.827554311182;5306.018386598492;6390.680465976776;6489.513286503437;6671.299513551201;6696.961970894519 09:00:23 INFO - PID 1711 | |5;yui.html;677.2381071294793;848.5290663681662;858.9012263589685;879.4064006795411;882.2721254964654;16084.919575402122;16680.66638667226;16696.081715505643;18677.252909883588;19296;16111.758323625156;17922.19350129244;18361;18579.628407431843;18675.813241867578;998.205025927404;1006.7785087280787;1021.9182465402787;1026.1944373666672;1033.9069483746462;1006.436395618456;1026.4611079183414;1028.300755486269;1031.6286136990677;1032.0556690628066;1078.7033565769398;1087.967360979168;1090.3403440918248;1090.738222826524;1092.1972350322508;1074;1084.8481212630238;1086.2993369276812;1093.4423444043596;1093.8577984861956;774.2103054884043;782.3154739602847;796.3350602247131;802.1577343789056;802.2178376083331;16434;16691.08199049052;16743.665126697397;16767.496975090766;16778.161091945476;17147.74278385825;17297.913510432492;17453.38913138034;17611.207495662726;17767.822321776875;12235.265884046985;14030.859691403057;14113.435462581485;14163.15021098727;14302.213378264192;3435.3129374125274;3505.24637203;3510.017195185352;3513.683768460851;3524.0837382280793;88800.99999999936;133274;133837;134119.6588034117;134991.32504337415;13558.661033474242;13787.655308617364;13805.17168969865;13817.51638692661;14021.29893505341;25615.23154305374;25737;26066.34834129162;26293.71328583927;26348;7034.909589013711;7370.815729606803;7835.882461763078;7847.1368149503505;7857.1357150713375;3835.7506762060375;3881.204353107606;3897.3179693553516;3899.395593682984;3909.5308562972627;4359.38968544404;4476.753778542212;4570.063137056895;4745.667803253808;4789.3294938708605;960.1358777100526;974.8147851908257;986.5067466266867;989.1048601015948;992.1417973452911;662.9436497897636;673.8416472129046;679.6329981809866;689.9068625735562;699.9815268784009;11054.613088541862;11122;11308.547658093748;11710.824337634773;11967.042636589053;572.6220694341714;602.4638072115821;662.5523231738666;664.35225654986;692.5083190934492;4882.804687812448;5382.623216374895;5428.891422171613;5572.721363931787;5709.914351284734;97293;107503.46248268709;110696.23259418418;110835.2415330919;113173;70539.64730176317;85521.71717424244;86818.56590717006;87249.56375218084;88882;70867.64566177136;70885.6455717718;72532;78641.60679196681;79207;10344.172466202835;10407;10734;10761.623343182951;10941.343519388862;39880.401793973695;39933.00167495836;40432.79783601063;40435.595644043184;40486.797566011985;51543.74228128835;52201.7389913048;52665.47334526606;53093.469065308855;53676;15973;16100.67798643997;16119.435819746253;16262.837371626132;16352.754708679617;17791;18159.727604085685;18272.360467383576;18388.264469421072;18492.260309587466;15675.921620391826;16408.671826563164;16582.83417165861;16815.243314051142;17693;16588.58528536748;16962.151892405083;16998.915005424893;17421.21604527774;17499.7375039372;13652.863471365159;16060;16090.758638620664;16782.916085419496;16955.576110597332;10467.214958877963;11234.88765112371;11253.549858005588;11368.033717133805;11521.88478115208;12955.481780728665;13188.80216796768;13207.405666744828;13221.933890330487;13246.271455070064 09:00:23 INFO - PID 1711 | __end_tp_report 09:00:23 INFO - PID 1711 | __start_cc_report 09:00:23 INFO - PID 1711 | _x_x_mozilla_cycle_collect,413 09:00:23 INFO - PID 1711 | __end_cc_report 09:00:23 INFO - PID 1711 | __startTimestamp1494432023852__endTimestamp 09:00:23 INFO - PID 1711 | 09:00:23 INFO - PID 1711 | ------- Summary: start ------- 09:00:23 INFO - PID 1711 | Number of tests: 6 09:00:23 INFO - PID 1711 | 09:00:23 INFO - PID 1711 | [#0] dojo.html Cycles:165 Average:6103.18 Median:6385.97 stddev:781.54 (12.2%) stddev-sans-first:758.16 09:00:23 INFO - PID 1711 | Values: 3565.8 3604.0 3610.6 3626.0 3630.1 6139.2 6140.1 6194.8 6202.0 6213.0 6139.2 6156.0 6172.5 6203.8 6240.2 5365.8 5389.6 5408.2 5423.2 5429.3 5426.4 5505.7 5532.9 5580.6 5594.9 5324.1 5456.8 5490.1 5527.7 5539.6 4170.2 4450.7 5384.4 5384.4 5456.2 4023.7 5398.4 5413.1 5454.0 5483.5 4921.3 5047.6 6092.7 6146.4 6217.3 6442.0 6697.5 6711.8 6715.9 6727.0 6661.0 6712.9 6760.0 6777.5 6794.9 6236.3 6238.8 6306.4 6308.6 6313.1 6979.3 6985.4 6990.3 7027.1 7034.8 5670.9 6255.2 6487.0 6535.0 6584.2 6106.9 6283.8 6793.4 6807.8 6811.4 6538.1 6666.3 6677.0 6689.8 6692.2 6416.6 6546.6 6612.4 6696.2 6707.8 5321.1 5366.6 5374.8 5380.6 5399.2 4685.3 4703.0 4736.1 4744.0 4793.2 6243.2 6314.6 6358.8 6455.6 6465.8 6702.5 6748.7 6770.0 6827.1 6853.9 6372.5 6391.8 6417.9 6421.1 6424.6 5824.3 5928.7 5979.9 6004.2 6019.2 6820.5 6950.5 6951.8 6974.9 7021.3 5602.3 6775.7 6787.4 6824.7 6842.6 6717.4 6819.9 6831.9 6834.5 7021.0 6672.0 6672.4 6681.3 6684.6 6689.1 4930.7 6517.9 6599.6 6615.1 6864.0 6534.5 6556.9 6567.9 6574.6 6580.1 6533.5 6543.9 6544.6 6549.9 6552.5 5289.7 5359.2 5663.2 5920.2 6410.3 5435.8 6287.7 6350.0 6460.7 6600.5 6386.0 6407.2 6424.4 6429.7 6456.4 09:00:23 INFO - PID 1711 | 09:00:23 INFO - PID 1711 | [#1] ext.html Cycles:180 Average:44438.30 Median:33865.83 stddev:78066.91 (230.5%) stddev-sans-first:78216.99 09:00:23 INFO - PID 1711 | Values: 749.9 813.2 826.8 1146.4 1269.2 37110.3 37742.4 38099.2 38231.0 38297.2 37872.2 38127.2 38185.0 38241.4 38326.8 7256.6 7836.8 7934.9 8347.2 8532.1 8477.7 8551.4 8590.6 8606.0 8625.7 8558.8 8584.1 8615.0 8635.5 8643.2 8539.2 8558.7 8578.7 8609.9 8610.3 8354.2 8415.7 8441.6 8470.9 8493.4 37129.6 37296.8 37389.4 37443.1 37455.4 3555.8 3600.5 3600.6 3601.5 3610.5 30389.1 30401.0 30404.4 30456.2 30580.3 2686.0 2721.6 2753.4 2770.3 2778.1 288488.0 290534.0 304908.0 312006.4 312123.4 39827.6 43767.8 46924.3 47016.5 47290.0 49040.8 49139.5 49161.5 49223.8 49243.8 11936.2 13913.6 13921.3 14058.9 14071.6 9665.8 11384.0 11404.4 11415.1 11551.7 13251.4 13382.3 13416.1 13422.7 13497.5 5201.8 6024.6 6026.0 6027.8 6086.9 975.6 999.5 1040.4 1043.6 1071.1 22612.9 23489.4 23714.8 23747.4 23919.4 1150.7 1178.0 1183.8 1187.3 1197.2 10165.5 11723.9 12125.9 12142.1 12264.2 294821.0 308016.9 330853.0 330997.0 332601.0 52270.0 52480.0 52556.5 52566.7 52570.0 1516.1 1543.0 1575.8 1576.1 1579.9 23166.7 23213.2 23222.5 23488.4 23511.8 36760.8 37135.0 37138.8 37221.6 37305.8 267527.0 268154.0 268432.0 270398.6 270526.0 32930.0 33280.0 34451.7 36103.3 36274.3 40253.0 43071.7 43320.0 44829.8 44998.6 27786.0 28843.7 29487.7 30683.0 30786.5 25678.7 29287.9 29568.6 30420.4 30575.7 35932.0 37424.0 37440.8 37486.0 37943.6 30132.4 30158.7 30282.5 30981.0 31230.4 20712.5 22374.4 22485.7 22539.0 22597.0 09:00:23 INFO - PID 1711 | 09:00:23 INFO - PID 1711 | [#2] jquery.html Cycles:180 Average:33977.01 Median:2260.59 stddev:83125.95 (3677.2%) stddev-sans-first:83320.88 09:00:23 INFO - PID 1711 | Values: 383.8 426.3 426.4 436.7 452.7 19076.4 20796.3 21544.4 21579.4 21598.9 21777.3 22433.0 22576.8 23373.5 23546.3 6073.4 6300.1 6301.3 6315.4 6318.7 5674.1 6186.9 6248.5 6255.8 6256.4 5267.0 6200.4 6201.1 6202.3 6211.6 5884.4 6137.5 6162.0 6194.7 6201.2 5271.8 5470.9 5472.6 5476.4 5509.9 27619.7 27621.0 27794.0 27794.4 28298.7 2119.9 2197.1 2203.4 2206.7 2208.3 17837.2 17932.0 18080.3 18291.5 18498.5 2145.7 2152.7 2201.1 2201.5 2209.2 139866.5 146429.0 158619.0 160465.0 162054.2 12333.1 12818.2 12932.3 12935.2 12959.9 16587.0 16596.3 16672.0 16706.6 16713.2 8838.6 8975.7 9090.9 9134.7 9207.7 8524.5 8600.8 8610.7 8731.7 8763.8 6883.7 8107.6 8176.9 8228.1 8238.1 2258.3 2262.9 2271.6 2274.2 2445.0 942.4 954.2 992.9 1031.8 1037.8 19960.4 20027.4 20872.0 20920.8 20930.9 942.1 970.0 991.4 1005.0 1020.0 10414.0 10723.2 10745.9 10807.5 10822.7 441699.4 464963.0 482302.0 504065.0 516817.4 123516.0 125213.0 125412.0 137713.6 141427.3 1071.0 1079.3 1206.9 1219.5 1219.5 18418.4 20680.9 21535.7 22133.3 22137.9 54846.5 58407.7 59016.1 59175.7 59986.7 82645.2 82806.0 83079.0 84036.6 84602.6 8916.2 8929.5 8958.3 9018.2 9044.7 13951.7 14007.9 14025.6 14372.6 14474.3 10388.9 11743.6 11859.0 12110.6 12289.3 10119.2 11417.3 11448.7 11549.3 11714.0 9666.3 9758.2 12323.6 12330.6 12432.8 8526.6 8713.7 8813.3 9020.9 9082.3 11853.6 12037.3 12037.6 12055.2 12177.8 09:00:23 INFO - PID 1711 | 09:00:23 INFO - PID 1711 | [#3] mootools.html Cycles:180 Average:7039.00 Median:3579.11 stddev:4482.87 (125.3%) stddev-sans-first:4475.10 09:00:23 INFO - PID 1711 | Values: 1355.2 1451.9 1469.6 1476.4 1486.4 11255.0 11612.5 11763.4 11771.4 11883.6 11499.7 11674.8 11684.0 11722.5 11734.8 2462.1 2482.0 2509.9 2512.7 2513.5 2415.3 2450.8 2476.2 2484.1 2489.0 2469.7 2496.5 2528.0 2530.5 2531.6 2179.4 2459.1 2460.1 2511.3 2520.3 2387.6 2393.0 2393.6 2400.7 2505.5 11835.7 11895.5 11901.5 11920.4 11955.8 6617.8 6774.3 6867.8 6872.0 6880.4 3712.6 4022.8 4065.7 4103.3 4107.3 1115.2 1129.3 1143.8 1182.9 1189.3 16059.4 16442.9 16509.3 16588.9 16697.6 5522.7 5551.2 5637.1 5746.2 5824.6 11843.7 11851.2 11904.0 11924.7 11969.3 6534.2 6576.1 6579.8 6596.1 6615.3 5962.7 6123.2 6157.4 6198.8 6215.5 4271.6 4367.1 4404.5 4405.5 4418.1 1103.8 1263.9 1274.8 1276.6 1293.9 711.0 726.3 804.6 828.4 840.4 5133.4 5948.0 6261.8 6268.3 6449.8 585.9 600.7 605.0 617.7 621.9 3445.7 3854.1 3928.5 3940.3 4110.4 14452.3 14528.5 15033.2 15072.8 15117.9 14842.6 14979.0 15065.2 15072.1 15102.8 7302.7 8355.5 8523.7 8552.2 8582.0 5322.6 6737.5 6986.6 7018.1 7065.9 12463.6 12634.7 12762.9 12818.9 12858.0 11356.7 13301.3 13395.0 13514.7 13789.3 8356.9 9009.8 9343.8 9467.6 9506.7 10559.0 10655.3 10691.3 10776.5 10789.1 7325.5 9044.8 9186.1 9202.7 9264.5 8986.3 9008.6 9035.8 9054.3 9101.7 8234.1 8241.0 8349.0 8367.5 8367.5 6096.0 6153.5 6209.6 6236.7 6249.8 5705.7 6120.3 6300.3 6311.7 6411.2 09:00:23 INFO - PID 1711 | 09:00:23 INFO - PID 1711 | [#4] prototype.html Cycles:180 Average:5486.37 Median:6066.62 stddev:1743.94 (28.7%) stddev-sans-first:1706.49 09:00:23 INFO - PID 1711 | Values: 397.4 402.4 404.6 404.7 409.3 6190.9 6353.7 6412.7 6462.5 6514.3 5305.0 5476.0 5685.5 6138.4 6384.6 4707.8 4774.5 4786.9 4797.2 4800.4 4637.0 4781.2 4793.6 4798.5 4826.8 4220.5 4306.8 4728.3 4730.9 4754.8 4151.7 4455.9 4746.7 4757.1 4781.7 3880.6 4014.8 4167.5 4226.3 4237.9 5872.3 5903.3 6144.8 6147.3 6179.9 5444.5 5453.8 5456.6 5476.5 5502.5 5657.4 5682.4 6583.7 6805.1 6915.8 3653.0 4417.2 4418.0 4450.2 4462.7 5803.7 6842.1 6910.7 7216.2 7266.5 3526.8 3770.4 4179.3 4413.7 4535.8 3924.4 4589.7 4599.4 4617.6 4664.3 5494.2 5501.1 5508.8 5525.8 5546.8 6152.3 6622.6 6808.6 6830.1 6852.3 1371.9 1456.9 1551.2 1568.6 1569.7 979.7 1018.8 1032.5 1033.6 1034.5 6556.2 6556.9 6557.0 6573.7 6602.0 5720.9 6461.7 7079.1 7086.0 7097.5 6063.3 6296.7 6495.4 6514.0 6535.4 3647.7 3671.3 3683.9 3702.0 3716.5 7077.0 7288.1 7369.6 7380.0 7480.5 6426.7 6958.6 7323.2 7373.2 7428.6 5796.7 7292.5 7387.6 7387.9 7391.5 5938.5 6872.6 7053.7 7130.6 7136.4 5784.0 6070.0 6085.5 6610.5 6973.3 7285.4 7352.7 7356.3 7377.7 7397.0 4564.9 4797.7 5195.1 5198.6 5214.9 5951.3 6873.7 7144.4 7147.0 7150.4 5652.6 6584.7 6772.0 6865.7 6906.2 4909.4 6674.8 6855.8 6855.9 6866.3 6386.2 6664.6 6711.5 6726.3 6745.4 6865.3 6875.0 6885.7 6894.3 6897.8 5306.0 6390.7 6489.5 6671.3 6697.0 09:00:23 INFO - PID 1711 | 09:00:23 INFO - PID 1711 | [#5] yui.html Cycles:180 Average:21407.09 Median:17779.41 stddev:30136.60 (169.5%) stddev-sans-first:30180.94 09:00:23 INFO - PID 1711 | Values: 677.2 848.5 858.9 879.4 882.3 16084.9 16680.7 16696.1 18677.3 19296.0 16111.8 17922.2 18361.0 18579.6 18675.8 998.2 1006.8 1021.9 1026.2 1033.9 1006.4 1026.5 1028.3 1031.6 1032.1 1078.7 1088.0 1090.3 1090.7 1092.2 1074.0 1084.8 1086.3 1093.4 1093.9 774.2 782.3 796.3 802.2 802.2 16434.0 16691.1 16743.7 16767.5 16778.2 17147.7 17297.9 17453.4 17611.2 17767.8 12235.3 14030.9 14113.4 14163.2 14302.2 3435.3 3505.2 3510.0 3513.7 3524.1 88801.0 133274.0 133837.0 134119.7 134991.3 13558.7 13787.7 13805.2 13817.5 14021.3 25615.2 25737.0 26066.3 26293.7 26348.0 7034.9 7370.8 7835.9 7847.1 7857.1 3835.8 3881.2 3897.3 3899.4 3909.5 4359.4 4476.8 4570.1 4745.7 4789.3 960.1 974.8 986.5 989.1 992.1 662.9 673.8 679.6 689.9 700.0 11054.6 11122.0 11308.5 11710.8 11967.0 572.6 602.5 662.6 664.4 692.5 4882.8 5382.6 5428.9 5572.7 5709.9 97293.0 107503.5 110696.2 110835.2 113173.0 70539.6 85521.7 86818.6 87249.6 88882.0 70867.6 70885.6 72532.0 78641.6 79207.0 10344.2 10407.0 10734.0 10761.6 10941.3 39880.4 39933.0 40432.8 40435.6 40486.8 51543.7 52201.7 52665.5 53093.5 53676.0 15973.0 16100.7 16119.4 16262.8 16352.8 17791.0 18159.7 18272.4 18388.3 18492.3 15675.9 16408.7 16582.8 16815.2 17693.0 16588.6 16962.2 16998.9 17421.2 17499.7 13652.9 16060.0 16090.8 16782.9 16955.6 10467.2 11234.9 11253.5 11368.0 11521.9 12955.5 13188.8 13207.4 13221.9 13246.3 09:00:23 INFO - PID 1711 | -------- Summary: end -------- 09:00:23 INFO - PID 1711 | 09:00:24 INFO - TEST-INFO | 1711: exit 0 09:00:24 INFO - TEST-OK | dromaeo_css | took 1113783ms 09:00:24 INFO - TEST-START | kraken 09:00:24 INFO - operating with platform_type : mac_ 09:00:24 INFO - Initialising browser for kraken test... 09:00:24 INFO - TEST-INFO | started process 1831 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpLOH6Rg/profile http://localhost:49243/getInfo.html) 09:00:26 INFO - PID 1831 | 2017-05-10 09:00:26.949 plugin-container[1832:10857] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 09:00:26 INFO - PID 1831 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:26 INFO - PID 1831 | 2017-05-10 09:00:26.954 plugin-container[1832:10857] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 09:00:26 INFO - PID 1831 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:26 INFO - PID 1831 | 2017-05-10 09:00:26.954 plugin-container[1832:10857] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:00:27 INFO - PID 1831 | __metrics Screen width/height:1600/1200 09:00:27 INFO - PID 1831 | colorDepth:24 09:00:27 INFO - PID 1831 | Browser inner width/height: 1024/691 09:00:27 INFO - PID 1831 | __metrics 09:00:27 INFO - PID 1831 | 09:00:27 INFO - PID 1831 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 09:00:27 INFO - PID 1831 | 09:00:28 INFO - TEST-INFO | 1831: exit 0 09:00:28 INFO - Browser initialized. 09:00:28 INFO - Running cycle 1/1 for kraken test... 09:00:28 INFO - TEST-INFO | started process 1834 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpLOH6Rg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:00:30 INFO - PID 1834 | 2017-05-10 09:00:30.217 plugin-container[1835:11014] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 09:00:30 INFO - PID 1834 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:30 INFO - PID 1834 | 2017-05-10 09:00:30.218 plugin-container[1835:11014] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.CFPasteboardClient' 09:00:30 INFO - PID 1834 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:30 INFO - PID 1834 | 2017-05-10 09:00:30.218 plugin-container[1835:11014] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:00:30 INFO - PID 1834 | 2017-05-10 09:00:30.961 plugin-container[1836:11057] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 09:00:30 INFO - PID 1834 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:30 INFO - PID 1834 | 2017-05-10 09:00:30.961 plugin-container[1836:11057] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 09:00:30 INFO - PID 1834 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:30 INFO - PID 1834 | 2017-05-10 09:00:30.961 plugin-container[1836:11057] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:00:40 INFO - PID 1834 | RSS: Main: 229335040 09:00:40 INFO - PID 1834 | 09:00:42 INFO - PID 1834 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 09:00:43 INFO - PID 1834 | RSS: Main: 242212864 09:00:43 INFO - PID 1834 | 09:00:46 INFO - PID 1834 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 09:00:47 INFO - PID 1834 | RSS: Main: 250646528 09:00:47 INFO - PID 1834 | 09:00:50 INFO - PID 1834 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 09:00:50 INFO - PID 1834 | RSS: Main: 252850176 09:00:50 INFO - PID 1834 | 09:00:52 INFO - PID 1834 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 09:00:53 INFO - PID 1834 | RSS: Main: 255180800 09:00:53 INFO - PID 1834 | 09:00:55 INFO - PID 1834 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:00:55 INFO - PID 1834 | RSS: Main: 255860736 09:00:55 INFO - PID 1834 | 09:00:58 INFO - PID 1834 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 09:00:59 INFO - PID 1834 | RSS: Main: 262057984 09:00:59 INFO - PID 1834 | 09:01:02 INFO - PID 1834 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 09:01:02 INFO - PID 1834 | RSS: Main: 263925760 09:01:02 INFO - PID 1834 | 09:01:05 INFO - PID 1834 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 09:01:06 INFO - PID 1834 | RSS: Main: 263376896 09:01:06 INFO - PID 1834 | 09:01:07 INFO - PID 1834 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:01:08 INFO - PID 1834 | RSS: Main: 262717440 09:01:08 INFO - PID 1834 | 09:01:10 INFO - PID 1834 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:01:11 INFO - PID 1834 | RSS: Main: 262606848 09:01:11 INFO - PID 1834 | 09:01:13 INFO - PID 1834 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:01:13 INFO - PID 1834 | RSS: Main: 262434816 09:01:13 INFO - PID 1834 | 09:01:16 INFO - PID 1834 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:01:17 INFO - PID 1834 | RSS: Main: 262152192 09:01:17 INFO - PID 1834 | 09:01:20 INFO - PID 1834 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:01:21 INFO - PID 1834 | RSS: Main: 262463488 09:01:21 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 09:01:23 INFO - PID 1834 | RSS: Main: 264298496 09:01:23 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | __start_tp_report 09:01:23 INFO - PID 1834 | _x_x_mozilla_page_load 09:01:23 INFO - PID 1834 | _x_x_mozilla_page_load_details 09:01:23 INFO - PID 1834 | |i|pagename|runs| 09:01:23 INFO - PID 1834 | |0;ai-astar;102;112;114;113;123;114;115;116;115;115 09:01:23 INFO - PID 1834 | |1;audio-beat-detection;176;143;147;149;107;109;112;110;147;110 09:01:23 INFO - PID 1834 | |2;audio-dft;194;199;194;203;205;211;212;211;201;196 09:01:23 INFO - PID 1834 | |3;audio-fft;69;58;58;81;58;59;65;58;72;60 09:01:23 INFO - PID 1834 | |4;audio-oscillator;87;70;81;71;68;69;90;71;72;98 09:01:23 INFO - PID 1834 | |5;imaging-gaussian-blur;89;90;89;89;89;88;89;91;88;83 09:01:23 INFO - PID 1834 | |6;imaging-darkroom;96;98;98;96;96;104;121;96;95;97 09:01:23 INFO - PID 1834 | |7;imaging-desaturate;93;89;91;92;95;91;92;93;92;90 09:01:23 INFO - PID 1834 | |8;json-parse-financial;51;50;48;52;49;50;50;48;51;71 09:01:23 INFO - PID 1834 | |9;json-stringify-tinderbox;58;56;58;77;84;82;79;82;61;57 09:01:23 INFO - PID 1834 | |10;stanford-crypto-aes;93;81;82;84;84;90;95;88;83;85 09:01:23 INFO - PID 1834 | |11;stanford-crypto-ccm;157;158;134;136;136;137;149;156;187;137 09:01:23 INFO - PID 1834 | |12;stanford-crypto-pbkdf2;186;184;184;185;226;186;207;194;210;229 09:01:23 INFO - PID 1834 | |13;stanford-crypto-sha256-iterative;69;67;67;59;84;92;99;94;78;66 09:01:23 INFO - PID 1834 | __end_tp_report 09:01:23 INFO - PID 1834 | __start_cc_report 09:01:23 INFO - PID 1834 | _x_x_mozilla_cycle_collect,1003 09:01:23 INFO - PID 1834 | __end_cc_report 09:01:23 INFO - PID 1834 | __startTimestamp1494432083420__endTimestamp 09:01:23 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | ------- Summary: start ------- 09:01:23 INFO - PID 1834 | Number of tests: 14 09:01:23 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | [#0] ai-astar Cycles:10 Average:113.90 Median:115.00 stddev:5.13 (4.5%) stddev-sans-first:3.15 09:01:23 INFO - PID 1834 | Values: 102.0 112.0 114.0 113.0 123.0 114.0 115.0 116.0 115.0 115.0 09:01:23 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | [#1] audio-beat-detection Cycles:10 Average:131.00 Median:145.00 stddev:24.29 (16.7%) stddev-sans-first:19.55 09:01:23 INFO - PID 1834 | Values: 176.0 143.0 147.0 149.0 107.0 109.0 112.0 110.0 147.0 110.0 09:01:23 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | [#2] audio-dft Cycles:10 Average:202.60 Median:204.00 stddev:7.01 (3.4%) stddev-sans-first:6.71 09:01:23 INFO - PID 1834 | Values: 194.0 199.0 194.0 203.0 205.0 211.0 212.0 211.0 201.0 196.0 09:01:23 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | [#3] audio-fft Cycles:10 Average:63.80 Median:62.50 stddev:7.91 (12.7%) stddev-sans-first:8.17 09:01:23 INFO - PID 1834 | Values: 69.0 58.0 58.0 81.0 58.0 59.0 65.0 58.0 72.0 60.0 09:01:23 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:76.50 stddev:10.60 (13.9%) stddev-sans-first:10.70 09:01:23 INFO - PID 1834 | Values: 87.0 70.0 81.0 71.0 68.0 69.0 90.0 71.0 72.0 98.0 09:01:23 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | [#5] imaging-gaussian-blur Cycles:10 Average:88.50 Median:89.00 stddev:2.12 (2.4%) stddev-sans-first:2.24 09:01:23 INFO - PID 1834 | Values: 89.0 90.0 89.0 89.0 89.0 88.0 89.0 91.0 88.0 83.0 09:01:23 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | [#6] imaging-darkroom Cycles:10 Average:99.70 Median:96.00 stddev:7.90 (8.2%) stddev-sans-first:8.27 09:01:23 INFO - PID 1834 | Values: 96.0 98.0 98.0 96.0 96.0 104.0 121.0 96.0 95.0 97.0 09:01:23 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | [#7] imaging-desaturate Cycles:10 Average:91.80 Median:92.00 stddev:1.69 (1.8%) stddev-sans-first:1.73 09:01:23 INFO - PID 1834 | Values: 93.0 89.0 91.0 92.0 95.0 91.0 92.0 93.0 92.0 90.0 09:01:23 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:50.50 stddev:6.80 (13.5%) stddev-sans-first:7.20 09:01:23 INFO - PID 1834 | Values: 51.0 50.0 48.0 52.0 49.0 50.0 50.0 48.0 51.0 71.0 09:01:23 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:78.00 stddev:12.22 (15.7%) stddev-sans-first:12.25 09:01:23 INFO - PID 1834 | Values: 58.0 56.0 58.0 77.0 84.0 82.0 79.0 82.0 61.0 57.0 09:01:23 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:86.50 stddev:4.79 (5.5%) stddev-sans-first:4.47 09:01:23 INFO - PID 1834 | Values: 93.0 81.0 82.0 84.0 84.0 90.0 95.0 88.0 83.0 85.0 09:01:23 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:152.50 stddev:16.63 (10.9%) stddev-sans-first:17.36 09:01:23 INFO - PID 1834 | Values: 157.0 158.0 134.0 136.0 136.0 137.0 149.0 156.0 187.0 137.0 09:01:23 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.10 Median:200.50 stddev:17.71 (8.8%) stddev-sans-first:18.14 09:01:23 INFO - PID 1834 | Values: 186.0 184.0 184.0 185.0 226.0 186.0 207.0 194.0 210.0 229.0 09:01:23 INFO - PID 1834 | 09:01:23 INFO - PID 1834 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.50 Median:81.00 stddev:13.96 (17.2%) stddev-sans-first:14.47 09:01:23 INFO - PID 1834 | Values: 69.0 67.0 67.0 59.0 84.0 92.0 99.0 94.0 78.0 66.0 09:01:23 INFO - PID 1834 | -------- Summary: end -------- 09:01:23 INFO - PID 1834 | 09:01:24 INFO - TEST-INFO | 1834: exit 0 09:01:24 INFO - TEST-OK | kraken | took 59851ms 09:01:24 INFO - SUITE-END | took 1173s 09:01:24 INFO - Completed test suite (00:19:33) 09:01:24 INFO - javascript benchmark 09:01:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7874.167057826761, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3565.8395372208265, 3603.981980090099, 3610.5667319921613, 3625.981870090649, 3630.0561853918007, 6139.171211886394, 6140.127515739758, 6194.814155575335, 6202.0076787713915, 6212.968935155318, 6139.15484235078, 6156.015037593986, 6172.5061995040405, 6203.751849926009, 6240.219972503437, 5365.839024829242, 5389.5688344932505, 5408.215808707735, 5423.159410291411, 5429.267048948391, 5426.375966763813, 5505.724713764316, 5532.889342213153, 5580.581456390787, 5594.860128496779, 5324.148136298174, 5456.7544460498975, 5490.149026900836, 5527.695976721278, 5539.639923404986, 4170.249355116078, 4450.688451808374, 5384.40771515133, 5384.407715151369, 5456.208849716769, 4023.7407699763285, 5398.352197736297, 5413.132469298086, 5454.018276710191, 5483.4516548344845, 4921.311016457699, 5047.621428392885, 6092.66490343031, 6146.416090471443, 6217.253929528486, 6442.0, 6697.464202863759, 6711.765088221889, 6715.9328406715795, 6726.966365168143, 6660.966695166541, 6712.899306510406, 6759.966200169017, 6777.457803375767, 6794.898076528856, 6236.282827474854, 6238.844028899314, 6306.369363063703, 6308.589941653778, 6313.053042043684, 6979.267176946449, 6985.406240469617, 6990.26602206761, 7027.121609798775, 7034.824129396704, 5670.914936275959, 6255.186825712628, 6487.0, 6535.019747037983, 6584.209894812653, 6106.908396374058, 6283.77466394058, 6793.422559082534, 6807.761728339485, 6811.352921472452, 6538.051982462516, 6666.333366663392, 6676.96661516699, 6689.765858195037, 6692.230393504712, 6416.647084410402, 6546.574472659262, 6612.437942774918, 6696.196456425163, 6707.765228216989, 5321.068812957717, 5366.6243362965, 5374.758135883894, 5380.569554435636, 5399.163129714901, 4685.320628508915, 4702.976485117552, 4736.100140973273, 4744.027474367816, 4793.18515852299, 6243.157173781481, 6314.55798094129, 6358.841028974312, 6455.644939528371, 6465.806025819233, 6702.463802895757, 6748.6963086660235, 6770.0, 6827.146606674166, 6853.862922741518, 6372.490200783926, 6391.840203994937, 6417.871642567123, 6421.101045853584, 6424.614523128627, 5824.271966004249, 5928.703564821828, 5979.850503737442, 6004.159417681528, 6019.247594050744, 6820.522563420612, 6950.54821436605, 6951.826204344932, 6974.860502790018, 7021.297870212938, 5602.299712535933, 6775.728970841111, 6787.389134977876, 6824.658767061528, 6842.55523390978, 6717.429018533284, 6819.863602727818, 6831.89752153728, 6834.487413443912, 7020.964895175492, 6671.966640166962, 6672.432843208382, 6681.331866813474, 6684.598924064571, 6689.063531105747, 4930.728809915489, 6517.902231466437, 6599.571027883172, 6615.073889655549, 6864.0047193155715, 6534.509911756732, 6556.868862622816, 6567.9014814776865, 6574.572652777554, 6580.078788969644, 6533.542652014314, 6543.934560654333, 6544.574602650812, 6549.934500654933, 6552.475801935739, 5289.68261904287, 5359.222912677562, 5663.235463212413, 5920.230370051866, 6410.294867564658, 5435.755391007335, 6287.654179020198, 6350.015747559136, 6460.676966151767, 6600.537962342782, 6385.968070159805, 6407.167068281094, 6424.357564243538, 6429.7428102875365, 6456.354364563506], "value": 6052.093026657543, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.9163708441305, 813.2396209544075, 826.8470332988397, 1146.3752255021013, 1269.2384569258452, 37110.25779484412, 37742.433863492, 38099.23801523968, 38231.0, 38297.23405531888, 37872.24255514895, 38127.23745525088, 38185.0, 38241.42637860427, 38326.80836595813, 7256.6371681415985, 7836.8040798980055, 7934.880976785324, 8347.207015333533, 8532.14678532148, 8477.66089356425, 8551.401401901869, 8590.570471476402, 8606.0, 8625.65497380104, 8558.78603034926, 8584.141585841428, 8614.956925215396, 8635.481871087723, 8643.178898004711, 8539.231469167742, 8558.743237702847, 8578.742637720878, 8609.913900861005, 8610.311175105977, 8354.248117669378, 8415.747527574182, 8441.620127094295, 8470.872936905951, 8493.447925884797, 37129.628703712886, 37296.81351593252, 37389.43915841264, 37443.06392340214, 37455.43816842722, 3555.7510974231814, 3600.531930848973, 3600.6399360064047, 3601.531800865897, 3610.4584312353095, 30389.088327350215, 30401.0, 30404.391912161635, 30456.238594035327, 30580.318082505455, 2686.0, 2721.5690567051465, 2753.366725653091, 2770.279727270924, 2778.055461143201, 288488.0, 290534.0, 304908.0, 312006.439967801, 312123.4393828039, 39827.60172398297, 43767.78116109399, 46924.296135558, 47016.529834701214, 47290.0, 49040.75479622579, 49139.50860491349, 49161.50838491641, 49223.75388123036, 49243.753781230866, 11936.164468487123, 13913.58259252224, 13921.30393480322, 14058.929705351613, 14071.648208794657, 9665.806683866424, 11384.0, 11404.429778511041, 11415.086793056536, 11551.653450396501, 13251.403686834115, 13382.330883455788, 13416.12795168311, 13422.731545369234, 13497.460101595827, 5201.829588342593, 6024.638521688625, 6026.005709058044, 6027.78902738402, 6086.908696369558, 975.6390135649713, 999.5052449037773, 1040.3549799124592, 1043.639944219243, 1071.1430855315873, 22612.886935565217, 23489.412764680677, 23714.7628523716, 23747.406314841923, 23919.402014949417, 1150.6605551362368, 1177.967533823075, 1183.7632473505334, 1187.281694574787, 1197.203859433467, 10165.491725413847, 11723.94223158151, 12125.939370303093, 12142.089343299287, 12264.202826816407, 294821.0, 308016.9198307988, 330853.0, 330997.0, 332601.0, 52270.0, 52480.0, 52556.47443525516, 52566.73716631392, 52570.0, 1516.1282262698992, 1542.9691406171814, 1575.7557578575338, 1576.054367379563, 1579.889407741447, 23166.652500212174, 23213.187538436072, 23222.535549289256, 23488.412789680395, 23511.76488235096, 36760.81619591885, 37135.0, 37138.81430592938, 37221.6277837229, 37305.81347093247, 267527.0, 268154.0, 268432.0, 270398.6480067587, 270526.0, 32930.0, 33280.0, 34451.655483445844, 36103.27793444064, 36274.27451451009, 40252.99367515835, 43071.707848764585, 43320.0, 44829.77585112053, 44998.550014500746, 27786.027489037788, 28843.711562884102, 29487.705122948497, 30683.0, 30786.538201927437, 25678.74321256838, 29287.85356073206, 29568.55647165251, 30420.39159216759, 30575.694243057285, 35932.0, 37424.0, 37440.812795935846, 37486.0, 37943.62056379511, 30132.397352052398, 30158.698413016467, 30282.54576181403, 30981.0, 31230.37539249248, 20712.48218794542, 22374.440638983506, 22485.66271505896, 22539.0, 22597.0], "value": 17264.217105876996, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.79275191396647, 426.2519278665941, 426.4094229492154, 436.6681322195131, 452.7233202369318, 19076.427707168772, 20796.272130475416, 21544.353669389904, 21579.352619421403, 21598.89200553997, 21777.3466795996, 22432.990515426805, 22576.774232257674, 23373.532529349402, 23546.293611191693, 6073.3622969588105, 6300.054991751228, 6301.275353334357, 6315.3684631536935, 6318.747250110013, 5674.148877668362, 6186.876262474748, 6248.531360147985, 6255.843603909893, 6256.374362563753, 5267.025600263944, 6200.410960958701, 6201.131841542165, 6202.255729312466, 6211.627302361872, 5884.352721200665, 6137.5089992800695, 6161.9691901540655, 6194.65929373882, 6201.193844800192, 5271.76277067529, 5470.890582188374, 5472.5621950243885, 5476.3702174250075, 5509.944900551023, 27619.723802762117, 27621.0, 27794.0, 27794.444111117868, 28298.717012829813, 2119.894005299744, 2197.0662468450846, 2203.3610253026604, 2206.6910632511463, 2208.2602328220064, 17837.197326120226, 17932.0, 18080.276788928426, 18291.45125646233, 18498.53753656156, 2145.7103291055664, 2152.698622192894, 2201.0535469748, 2201.4716468047714, 2209.1605190027685, 139866.50333741738, 146429.0, 158619.0, 160465.0, 162054.18972905178, 12333.136680432284, 12818.166819156724, 12932.288724120075, 12935.223886566837, 12959.935200324127, 16587.004779713257, 16596.336146554244, 16671.99968001924, 16706.58233544171, 16713.247903844356, 8838.646454141763, 8975.685850995184, 9090.86363704545, 9134.680286190085, 9207.723768286962, 8524.522497997583, 8600.827983440297, 8610.741677749678, 8731.694390696422, 8763.780905477286, 6883.724651014004, 8107.55408452529, 8176.877346839802, 8228.094909559944, 8238.134995825472, 2258.2999270226283, 2262.8868556572106, 2271.6478945763433, 2274.1812947338944, 2444.9755502445105, 942.3686130292721, 954.1508057828538, 992.9106380425796, 1031.7626945802508, 1037.8183817832, 19960.401187964384, 20027.399178024683, 20872.0, 20920.79079209219, 20930.89534552348, 942.0546571330182, 969.966051188205, 991.3803872579638, 1005.0, 1019.9592016319264, 10414.0, 10723.195760318007, 10745.871683473122, 10807.459627018774, 10822.72061872668, 441699.37450938264, 464963.0, 482302.0, 504065.0, 516817.41591291805, 123516.0, 125213.0, 125412.0, 137713.6228637721, 141427.29286353503, 1070.9678709638724, 1079.3254216114929, 1206.9215500992407, 1219.481720268882, 1219.4939100273286, 18418.447446576625, 20680.896595516926, 21535.676964845854, 22133.33599992003, 22137.893105344345, 54846.451535484135, 58407.70796145992, 59016.1147582778, 59175.70412147912, 59986.70006649939, 82645.17354826375, 82806.0, 83079.0, 84036.57981710053, 84602.57698711714, 8916.19754222123, 8929.464232145832, 8958.283337333129, 9018.23345015681, 9044.683436079706, 13951.651208719863, 14007.859921401063, 14025.649358766112, 14372.568822935329, 14474.34865431037, 10388.896111039096, 11743.588974385855, 11859.0, 12110.576129835414, 12289.262644241375, 10119.190464762949, 11417.257878237888, 11448.656540303804, 11549.307041577533, 11714.0, 9666.323357364918, 9758.210679360342, 12323.568675096329, 12330.63008109758, 12432.75134497323, 8526.573671316286, 8713.651453941771, 8813.25087367581, 9020.909790902007, 9082.318826087938, 11853.644390668294, 12037.337946413032, 12037.578684745991, 12055.15613907018, 12177.756444871231], "value": 10492.123308029042, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.1801160298019, 1451.912885226886, 1469.5517867050553, 1476.4315738440698, 1486.4425840309884, 11255.0, 11612.477438515267, 11763.35301558414, 11771.352575608349, 11883.64349069527, 11499.655010349681, 11674.766504669902, 11684.0, 11722.53109875606, 11734.765304693901, 2462.0644155221034, 2482.0, 2509.899604015837, 2512.736162702917, 2513.49730053989, 2415.287490190398, 2450.840695354805, 2476.207613563651, 2484.1181380609896, 2489.004398240709, 2469.7283298837115, 2496.5381404440236, 2527.988804478205, 2530.493901219754, 2531.6076008218756, 2179.4442417183677, 2459.1270099114827, 2460.0774709483944, 2511.2717311979504, 2520.2943175910773, 2387.558301714171, 2393.0, 2393.5811233034324, 2400.651905473714, 2505.5114252720728, 11835.704107397298, 11895.52417903283, 11901.523939042429, 11920.403979801062, 11955.760884782343, 6617.8014659560295, 6774.322567743236, 6867.793966181022, 6872.037914691947, 6880.380765731058, 3712.573054098786, 4022.7988600569856, 4065.715399922007, 4103.322951712964, 4107.342825147962, 1115.1636272795404, 1129.2885482146278, 1143.7712457508449, 1182.875798041202, 1189.3458597771191, 16059.437919672755, 16442.917785410995, 16509.257083431276, 16588.917055414644, 16697.580705640034, 5522.723863806874, 5551.167324901297, 5637.069883469264, 5746.166805813217, 5824.563157763185, 11843.703907402383, 11851.229670071418, 11903.988161006238, 11924.701882452835, 11969.281843089442, 6534.150560427114, 6576.0793488911595, 6579.83550411234, 6596.142501474874, 6615.338466153346, 5962.701864906824, 6123.203983482208, 6157.353477884847, 6198.845028874314, 6215.533834962396, 4271.5514870938105, 4367.082912588392, 4404.493483249404, 4405.471343438744, 4418.116376724668, 1103.7902798468265, 1263.8736126387287, 1274.7577960187534, 1276.5746075898992, 1293.8641442648575, 710.9502334836512, 726.3281464645182, 804.6057431858347, 828.4490813608883, 840.3949156607233, 5133.435322114565, 5948.018576934844, 6261.78083767066, 6268.279147897959, 6449.774257900951, 585.9414058594107, 600.6996501749126, 604.9637021778707, 617.7405489694339, 621.8911690454153, 3445.6554344565343, 3854.094287842355, 3928.489296391508, 3940.271049855786, 4110.362893751473, 14452.277386130652, 14528.491502797562, 15033.248337583078, 15072.773908391382, 15117.924410377878, 14842.628934276729, 14979.026363286352, 15065.246737663292, 15072.095674259579, 15102.773458398133, 7302.671379787922, 8355.456895301786, 8523.74428767138, 8552.187542183483, 8582.0, 5322.600804939568, 6737.494687898526, 6986.615736134561, 7018.087648605649, 7065.929340706527, 12463.629000810084, 12634.684132896384, 12762.87237127654, 12818.871811281768, 12858.0, 11356.716082098013, 13301.26843023643, 13395.0, 13514.729705406033, 13789.310534473438, 8356.913601231801, 9009.81980360376, 9343.81312373735, 9467.621295148392, 9506.714798556055, 10559.0, 10655.30740501865, 10691.25161238737, 10776.461176940964, 10789.136869050613, 7325.523840950321, 9044.773880653036, 9186.127317905055, 9202.677906273248, 9264.490453025148, 8986.281097512314, 9008.59461324255, 9035.819283614423, 9054.320925930713, 9101.681441149793, 8234.135415781257, 8241.0, 8348.998120225611, 8367.456115352483, 8367.497950123012, 6095.969520152371, 6153.4769544587425, 6209.565330427007, 6236.688165591793, 6249.843753906189, 5705.686187259741, 6120.3267640558615, 6300.2754683212015, 6311.715972781328, 6411.230652321751], "value": 5258.164986192787, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.38802244543405, 402.4265421773972, 404.6055096281126, 404.67625899280586, 409.2714967358104, 6190.938090619093, 6353.650549219791, 6412.711427985741, 6462.450691691212, 6514.315996820336, 5305.0, 5475.972620136904, 5685.516731077863, 6138.416850399205, 6384.585001974869, 4707.83522576709, 4774.450938142106, 4786.904261914759, 4797.208460603996, 4800.399950006249, 4636.976815115937, 4781.187198176319, 4793.616510679155, 4798.5441383068555, 4826.806927722904, 4220.514640816315, 4306.8277268909205, 4728.314394412808, 4730.90538189236, 4754.8365493700285, 4151.712968979626, 4455.866324010285, 4746.667733258673, 4757.147709336475, 4781.665283430161, 3880.553736320331, 4014.7992600369867, 4167.541570427251, 4226.344916537942, 4237.851675191354, 5872.265966754156, 5903.321118071433, 6144.815655530341, 6147.313734795296, 6179.87640247197, 5444.549952521342, 5453.836384908459, 5456.645318054353, 5476.452354764531, 5502.477264659851, 5657.405972372924, 5682.374938756733, 6583.736650533973, 6805.115335006467, 6915.75794847183, 3653.0, 4417.226985277595, 4417.983863711333, 4450.2434586120435, 4462.709923854939, 5803.709814509258, 6842.076319696827, 6910.723571057151, 7216.201226184581, 7266.45501587383, 3526.7531272810656, 3770.434434834797, 4179.310413781692, 4413.7351758894565, 4535.7958891849285, 3924.372100463912, 4589.714879833652, 4599.379083823708, 4617.607503362185, 4664.323673067454, 5494.203340515603, 5501.119820828648, 5508.807191748269, 5525.784327447955, 5546.750396232099, 6152.323244443108, 6622.569532980341, 6808.557443766139, 6830.077939478107, 6852.28051054642, 1371.8971077169251, 1456.8907331950147, 1551.2166355990378, 1568.5764843492193, 1569.723900344505, 979.6627603321557, 1018.8318927377048, 1032.4940779018227, 1033.560736686905, 1034.4930983818026, 6556.213254409501, 6556.934430655632, 6556.95088785792, 6573.704183311763, 6601.966990165114, 5720.942790572041, 6461.709223084972, 7079.079719636415, 7085.96457017722, 7097.538659987084, 6063.272407311151, 6296.740651869644, 6495.447886929664, 6513.990331498625, 6535.444487218545, 3647.6899463545365, 3671.30245253401, 3683.9263214735556, 3702.018964974284, 3716.5168528091176, 7076.964615176891, 7288.052553168161, 7369.6315184240575, 7380.0, 7480.466504366701, 6426.678666066678, 6958.55282775068, 7323.231060738546, 7373.225811289844, 7428.591427471433, 5796.710164491843, 7292.468581597924, 7387.556746595222, 7387.889181662279, 7391.482596218429, 5938.524918006463, 6872.62200578973, 7053.717851285891, 7130.643467826484, 7136.3934065602925, 5783.975608536978, 6069.969650151897, 6085.543584231112, 6610.471162306908, 6973.302669733189, 7285.380742636723, 7352.70589176427, 7356.264373562601, 7377.741779037708, 7397.0, 4564.927242098171, 4797.736124513046, 5195.077716324151, 5198.610104242121, 5214.895702085862, 5951.345352011362, 6873.656317184221, 7144.3570078693165, 7146.964265178641, 7150.42796576283, 5652.558597557597, 6584.736610535525, 6771.966140169267, 6865.725370985105, 6906.171259448898, 4909.410870695539, 6674.798536263325, 6855.794326170223, 6855.897161542481, 6866.279040700854, 6386.201724784402, 6664.566803157779, 6711.496637752286, 6726.293739157318, 6745.392914637706, 6865.347791959756, 6875.0, 6885.690143943635, 6894.276101009523, 6897.827554311182, 5306.018386598492, 6390.680465976776, 6489.513286503437, 6671.299513551201, 6696.961970894519], "value": 4935.906857025642, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.2381071294793, 848.5290663681662, 858.9012263589685, 879.4064006795411, 882.2721254964654, 16084.919575402122, 16680.66638667226, 16696.081715505643, 18677.252909883588, 19296.0, 16111.758323625156, 17922.19350129244, 18361.0, 18579.628407431843, 18675.813241867578, 998.205025927404, 1006.7785087280787, 1021.9182465402787, 1026.1944373666672, 1033.9069483746462, 1006.436395618456, 1026.4611079183414, 1028.300755486269, 1031.6286136990677, 1032.0556690628066, 1078.7033565769398, 1087.967360979168, 1090.3403440918248, 1090.738222826524, 1092.1972350322508, 1074.0, 1084.8481212630238, 1086.2993369276812, 1093.4423444043596, 1093.8577984861956, 774.2103054884043, 782.3154739602847, 796.3350602247131, 802.1577343789056, 802.2178376083331, 16434.0, 16691.08199049052, 16743.665126697397, 16767.496975090766, 16778.161091945476, 17147.74278385825, 17297.913510432492, 17453.38913138034, 17611.207495662726, 17767.822321776875, 12235.265884046985, 14030.859691403057, 14113.435462581485, 14163.15021098727, 14302.213378264192, 3435.3129374125274, 3505.24637203, 3510.017195185352, 3513.683768460851, 3524.0837382280793, 88800.99999999936, 133274.0, 133837.0, 134119.6588034117, 134991.32504337415, 13558.661033474242, 13787.655308617364, 13805.17168969865, 13817.51638692661, 14021.29893505341, 25615.23154305374, 25737.0, 26066.34834129162, 26293.71328583927, 26348.0, 7034.909589013711, 7370.815729606803, 7835.882461763078, 7847.1368149503505, 7857.1357150713375, 3835.7506762060375, 3881.204353107606, 3897.3179693553516, 3899.395593682984, 3909.5308562972627, 4359.38968544404, 4476.753778542212, 4570.063137056895, 4745.667803253808, 4789.3294938708605, 960.1358777100526, 974.8147851908257, 986.5067466266867, 989.1048601015948, 992.1417973452911, 662.9436497897636, 673.8416472129046, 679.6329981809866, 689.9068625735562, 699.9815268784009, 11054.613088541862, 11122.0, 11308.547658093748, 11710.824337634773, 11967.042636589053, 572.6220694341714, 602.4638072115821, 662.5523231738666, 664.35225654986, 692.5083190934492, 4882.804687812448, 5382.623216374895, 5428.891422171613, 5572.721363931787, 5709.914351284734, 97293.0, 107503.46248268709, 110696.23259418418, 110835.2415330919, 113173.0, 70539.64730176317, 85521.71717424244, 86818.56590717006, 87249.56375218084, 88882.0, 70867.64566177136, 70885.6455717718, 72532.0, 78641.60679196681, 79207.0, 10344.172466202835, 10407.0, 10734.0, 10761.623343182951, 10941.343519388862, 39880.401793973695, 39933.00167495836, 40432.79783601063, 40435.595644043184, 40486.797566011985, 51543.74228128835, 52201.7389913048, 52665.47334526606, 53093.469065308855, 53676.0, 15973.0, 16100.67798643997, 16119.435819746253, 16262.837371626132, 16352.754708679617, 17791.0, 18159.727604085685, 18272.360467383576, 18388.264469421072, 18492.260309587466, 15675.921620391826, 16408.671826563164, 16582.83417165861, 16815.243314051142, 17693.0, 16588.58528536748, 16962.151892405083, 16998.915005424893, 17421.21604527774, 17499.7375039372, 13652.863471365159, 16060.0, 16090.758638620664, 16782.916085419496, 16955.576110597332, 10467.214958877963, 11234.88765112371, 11253.549858005588, 11368.033717133805, 11521.88478115208, 12955.481780728665, 13188.80216796768, 13207.405666744828, 13221.933890330487, 13246.271455070064], "value": 8376.85335920795, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1502.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 114.0, 113.0, 123.0, 114.0, 115.0, 116.0, 115.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 143.0, 147.0, 149.0, 107.0, 109.0, 112.0, 110.0, 147.0, 110.0], "value": 131.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 199.0, 194.0, 203.0, 205.0, 211.0, 212.0, 211.0, 201.0, 196.0], "value": 202.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 58.0, 58.0, 81.0, 58.0, 59.0, 65.0, 58.0, 72.0, 60.0], "value": 63.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 81.0, 71.0, 68.0, 69.0, 90.0, 71.0, 72.0, 98.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 89.0, 89.0, 89.0, 88.0, 89.0, 91.0, 88.0, 83.0], "value": 88.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 98.0, 96.0, 96.0, 104.0, 121.0, 96.0, 95.0, 97.0], "value": 99.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 89.0, 91.0, 92.0, 95.0, 91.0, 92.0, 93.0, 92.0, 90.0], "value": 91.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 48.0, 52.0, 49.0, 50.0, 50.0, 48.0, 51.0, 71.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 58.0, 77.0, 84.0, 82.0, 79.0, 82.0, 61.0, 57.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 81.0, 82.0, 84.0, 84.0, 90.0, 95.0, 88.0, 83.0, 85.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 158.0, 134.0, 136.0, 136.0, 137.0, 149.0, 156.0, 187.0, 137.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 184.0, 185.0, 226.0, 186.0, 207.0, 194.0, 210.0, 229.0], "value": 199.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 67.0, 59.0, 84.0, 92.0, 99.0, 94.0, 78.0, 66.0], "value": 77.5, "unit": "score"}]}]} 09:01:24 INFO - Return code: 0 09:01:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:01:24 INFO - # TBPL SUCCESS # 09:01:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:01:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:01:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:01:24 INFO - 2017-05-10 09:01:24.777 screenresolution[1847:11442] starting screenresolution argv=screenresolution get 09:01:24 INFO - 2017-05-10 09:01:24.780 screenresolution[1847:11442] Display 0: 1600x1200x32@60 09:01:24 INFO - 2017-05-10 09:01:24.786 screenresolution[1848:11445] starting screenresolution argv=screenresolution list 09:01:24 INFO - Available Modes on Display 0 09:01:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:01:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:01:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:01:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:01:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:01:25 INFO - Intel Iris: 09:01:25 INFO - Chipset Model: Intel Iris 09:01:25 INFO - Type: GPU 09:01:25 INFO - Bus: Built-In 09:01:25 INFO - VRAM (Dynamic, Max): 1536 MB 09:01:25 INFO - Vendor: Intel (0x8086) 09:01:25 INFO - Device ID: 0x0a2e 09:01:25 INFO - Revision ID: 0x0009 09:01:25 INFO - Displays: 09:01:25 INFO - Display: 09:01:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:01:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:01:25 INFO - Display Serial Number: 1600x1200 60 09:01:25 INFO - Main Display: Yes 09:01:25 INFO - Mirror: Off 09:01:25 INFO - Online: Yes 09:01:25 INFO - Rotation: Supported 09:01:25 INFO - Return code: 0 09:01:25 INFO - Running post-action listener: _resource_record_post_action 09:01:25 INFO - [mozharness: 2017-05-10 16:01:25.021339Z] Finished run-tests step (success) 09:01:25 INFO - Running post-run listener: _resource_record_post_run 09:01:25 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 35152896; Write bytes: 534497792; Read time: 326; Write time: 1832 09:01:25 INFO - TinderboxPrint: CPU usage
54.4% 09:01:25 INFO - TinderboxPrint: I/O read bytes / time
35,152,896 / 326 09:01:25 INFO - TinderboxPrint: I/O write bytes / time
534,497,792 / 1,832 09:01:25 INFO - TinderboxPrint: CPU idle
2,179.3 (45.6%) 09:01:25 INFO - TinderboxPrint: CPU system
970.8 (20.3%) 09:01:25 INFO - TinderboxPrint: CPU user
1,631.4 (34.1%) 09:01:25 INFO - TinderboxPrint: Swap in / out
245,526,528 / 0 09:01:25 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 207778304; Write bytes: 208492032; Read time: 15727; Write time: 486 09:01:25 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 33182720; Write bytes: 315257856; Read time: 301; Write time: 1308 09:01:25 INFO - Running post-run listener: _upload_blobber_files 09:01:25 INFO - Blob upload gear active. 09:01:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:01:25 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 09:01:25 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:01:25 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:01:25 INFO - (blobuploader) - INFO - Open directory for files ... 09:01:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:01:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:01:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:01:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:01:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:01:26 INFO - (blobuploader) - INFO - Done attempting. 09:01:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:01:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:01:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:01:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:01:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:01:27 INFO - (blobuploader) - INFO - Done attempting. 09:01:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:01:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:01:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:01:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:01:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:01:28 INFO - (blobuploader) - INFO - Done attempting. 09:01:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:01:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:01:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:01:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:01:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:01:30 INFO - (blobuploader) - INFO - Done attempting. 09:01:30 INFO - (blobuploader) - INFO - Iteration through files over. 09:01:30 INFO - Return code: 0 09:01:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:01:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:01:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c5b3298a8a392e8d07ba9c1e5d2aed5f32e310e1b56a042d0e5e543921cc7839e67d43ab4e9bbe482bed2247b812631a10fac67cfc82c5c3295be8d2814106b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b47ab8348309788194f30411d35b443cadacbed5bc877d96430b445ce9468e496a91007c3da883a3ddae988e15ad6e05b2196949b3a7fe1bcf9b6951b118b0ce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19227c52e19806e6c99cf0638ae18627466bbc5f7a15e0d979a6bc2734646cfc65406e915eb9cf55892d3cf8f48e121542253ef9872c2ce014b9ddd203f1d109", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79ff07d5382a1b0096ebd110e94a2bb6c1b8fa00a846314067d750b5cc287894b0f30c88b2ecd81a9ce953066e90262d5d8b7e89cf3ca06b825fd029e92dbfc8"} 09:01:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:01:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:01:30 INFO - Contents: 09:01:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c5b3298a8a392e8d07ba9c1e5d2aed5f32e310e1b56a042d0e5e543921cc7839e67d43ab4e9bbe482bed2247b812631a10fac67cfc82c5c3295be8d2814106b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b47ab8348309788194f30411d35b443cadacbed5bc877d96430b445ce9468e496a91007c3da883a3ddae988e15ad6e05b2196949b3a7fe1bcf9b6951b118b0ce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19227c52e19806e6c99cf0638ae18627466bbc5f7a15e0d979a6bc2734646cfc65406e915eb9cf55892d3cf8f48e121542253ef9872c2ce014b9ddd203f1d109", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79ff07d5382a1b0096ebd110e94a2bb6c1b8fa00a846314067d750b5cc287894b0f30c88b2ecd81a9ce953066e90262d5d8b7e89cf3ca06b825fd029e92dbfc8"} 09:01:30 INFO - Running post-run listener: copy_logs_to_upload_dir 09:01:30 INFO - Copying logs to upload dir... 09:01:30 INFO - mkdir: /builds/slave/test/build/upload/logs 09:01:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.882509 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-05-10 09:01:30.988236) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-10 09:01:30.992014) ========= 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.gY6bb4FO7U/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.wsZedlf1cF/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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/Firefox/sha512/9c5b3298a8a392e8d07ba9c1e5d2aed5f32e310e1b56a042d0e5e543921cc7839e67d43ab4e9bbe482bed2247b812631a10fac67cfc82c5c3295be8d2814106b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b47ab8348309788194f30411d35b443cadacbed5bc877d96430b445ce9468e496a91007c3da883a3ddae988e15ad6e05b2196949b3a7fe1bcf9b6951b118b0ce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19227c52e19806e6c99cf0638ae18627466bbc5f7a15e0d979a6bc2734646cfc65406e915eb9cf55892d3cf8f48e121542253ef9872c2ce014b9ddd203f1d109", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79ff07d5382a1b0096ebd110e94a2bb6c1b8fa00a846314067d750b5cc287894b0f30c88b2ecd81a9ce953066e90262d5d8b7e89cf3ca06b825fd029e92dbfc8"} build_url:https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011306 build_url: 'https://queue.taskcluster.net/v1/task/E_59EUdWTLWqPg30BD7Xiw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c5b3298a8a392e8d07ba9c1e5d2aed5f32e310e1b56a042d0e5e543921cc7839e67d43ab4e9bbe482bed2247b812631a10fac67cfc82c5c3295be8d2814106b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b47ab8348309788194f30411d35b443cadacbed5bc877d96430b445ce9468e496a91007c3da883a3ddae988e15ad6e05b2196949b3a7fe1bcf9b6951b118b0ce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19227c52e19806e6c99cf0638ae18627466bbc5f7a15e0d979a6bc2734646cfc65406e915eb9cf55892d3cf8f48e121542253ef9872c2ce014b9ddd203f1d109", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79ff07d5382a1b0096ebd110e94a2bb6c1b8fa00a846314067d750b5cc287894b0f30c88b2ecd81a9ce953066e90262d5d8b7e89cf3ca06b825fd029e92dbfc8"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-10 09:01:31.036716) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 09:01:31.037511) ========= 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.gY6bb4FO7U/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.wsZedlf1cF/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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.005163 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 09:01:31.090618) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-10 09:01:31.090972) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-10 09:01:31.091318) ========= ========= Total master_lag: 0.20 =========