builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0267 starttime: 1495807341.71 results: success (0) buildid: 20170526053516 builduid: 3d07de89571e499288f5e96b117da1d8 revision: 83e94c015805483d998deddc86f3a541edba50d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 07:02:21.708431) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 07:02:21.708926) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 07:02:21.709225) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 07:02:21.736891) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 07:02:21.737492) ========= 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.tFvcbk0W4q/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.1ns17MAjmE/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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-26 07:02:21-- 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% 21.9M=0.001s 2017-05-26 07:02:21 (21.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126859 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 07:02:21.888933) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 07:02:21.889293) ========= 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.tFvcbk0W4q/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.1ns17MAjmE/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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.027283 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 07:02:21.939663) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-26 07:02:21.940276) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 83e94c015805483d998deddc86f3a541edba50d7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 83e94c015805483d998deddc86f3a541edba50d7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tFvcbk0W4q/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.1ns17MAjmE/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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-26 07:02:21,997 truncating revision to first 12 chars 2017-05-26 07:02:21,998 Setting DEBUG logging. 2017-05-26 07:02:21,998 attempt 1/10 2017-05-26 07:02:21,998 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/83e94c015805?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-26 07:02:23,572 unpacking tar archive at: mozilla-beta-83e94c015805/testing/mozharness/ program finished with exit code 0 elapsedTime=1.863159 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-26 07:02:23.828755) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 07:02:23.829144) ========= script_repo_revision: 83e94c015805483d998deddc86f3a541edba50d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 07:02:23.829524) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 07:02:23.829787) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 07:02:23.849862) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-05-26 07:02:23.850511) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tFvcbk0W4q/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.1ns17MAjmE/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 07:02:23 INFO - MultiFileLogger online at 20170526 07:02:23 in /builds/slave/test 07:02:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 07:02:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:02:24 INFO - {'append_to_log': False, 07:02:24 INFO - 'base_work_dir': '/builds/slave/test', 07:02:24 INFO - 'blob_upload_branch': 'Mozilla-Beta', 07:02:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:02:24 INFO - 'branch': 'Mozilla-Beta', 07:02:24 INFO - 'buildbot_json_path': 'buildprops.json', 07:02:24 INFO - 'config_files': ('talos/mac_config.py',), 07:02:24 INFO - 'default_actions': ('clobber', 07:02:24 INFO - 'read-buildbot-config', 07:02:24 INFO - 'download-and-extract', 07:02:24 INFO - 'populate-webroot', 07:02:24 INFO - 'create-virtualenv', 07:02:24 INFO - 'install', 07:02:24 INFO - 'run-tests'), 07:02:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:02:24 INFO - 'download_minidump_stackwalk': True, 07:02:24 INFO - 'download_symbols': 'ondemand', 07:02:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:02:24 INFO - 'tooltool.py': '/tools/tooltool.py', 07:02:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:02:24 INFO - '/tools/misc-python/virtualenv.py')}, 07:02:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:02:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:02:24 INFO - 'gecko_profile': False, 07:02:24 INFO - 'gecko_profile_interval': 0, 07:02:24 INFO - 'installer_path': 'installer.exe', 07:02:24 INFO - 'log_level': 'info', 07:02:24 INFO - 'log_name': 'talos', 07:02:24 INFO - 'log_to_console': True, 07:02:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:02:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:02:24 INFO - 'opt_config_files': (), 07:02:24 INFO - 'pip_index': False, 07:02:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:02:24 INFO - 'cmd': ('bash', 07:02:24 INFO - '-c', 07:02:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:02:24 INFO - 'enabled': True, 07:02:24 INFO - 'halt_on_failure': False, 07:02:24 INFO - 'name': 'check_screen_resolution'},), 07:02:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:02:24 INFO - 'cmd': ('bash', 07:02:24 INFO - '-c', 07:02:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:02:24 INFO - 'enabled': True, 07:02:24 INFO - 'halt_on_failure': False, 07:02:24 INFO - 'name': 'check_screen_resolution'},), 07:02:24 INFO - 'run_cmd_checks_enabled': True, 07:02:24 INFO - 'suite': 'dromaeojs-e10s', 07:02:24 INFO - 'system_bits': '32', 07:02:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:02:24 INFO - 'title': 't-yosemite-r7-0267', 07:02:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:02:24 INFO - 'use_talos_json': True, 07:02:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:02:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:02:24 INFO - 'work_dir': 'build'} 07:02:24 INFO - [mozharness: 2017-05-26 14:02:24.009110Z] Running clobber step. 07:02:24 INFO - Running pre-action listener: _resource_record_pre_action 07:02:24 INFO - Running main action method: clobber 07:02:24 INFO - rmtree: /builds/slave/test/build 07:02:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:02:29 INFO - Running post-action listener: _resource_record_post_action 07:02:29 INFO - [mozharness: 2017-05-26 14:02:29.160108Z] Finished clobber step (success) 07:02:29 INFO - [mozharness: 2017-05-26 14:02:29.160255Z] Running read-buildbot-config step. 07:02:29 INFO - Running pre-action listener: _resource_record_pre_action 07:02:29 INFO - Running main action method: read_buildbot_config 07:02:29 INFO - Using buildbot properties: 07:02:29 INFO - { 07:02:29 INFO - "project": "", 07:02:29 INFO - "product": "firefox", 07:02:29 INFO - "script_repo_revision": "production", 07:02:29 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 07:02:29 INFO - "repository": "", 07:02:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 07:02:29 INFO - "buildid": "20170526053516", 07:02:29 INFO - "pgo_build": "False", 07:02:29 INFO - "basedir": "/builds/slave/test", 07:02:29 INFO - "buildnumber": 3, 07:02:29 INFO - "slavename": "t-yosemite-r7-0267", 07:02:29 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 07:02:29 INFO - "platform": "yosemite_r7", 07:02:29 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 07:02:29 INFO - "branch": "mozilla-beta", 07:02:29 INFO - "repo_path": "releases/mozilla-beta", 07:02:29 INFO - "revision": "83e94c015805483d998deddc86f3a541edba50d7", 07:02:29 INFO - "stage_platform": "macosx64", 07:02:29 INFO - "builduid": "3d07de89571e499288f5e96b117da1d8", 07:02:29 INFO - "slavebuilddir": "test" 07:02:29 INFO - } 07:02:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 07:02:29 INFO - Running post-action listener: _resource_record_post_action 07:02:29 INFO - [mozharness: 2017-05-26 14:02:29.163493Z] Finished read-buildbot-config step (success) 07:02:29 INFO - [mozharness: 2017-05-26 14:02:29.163606Z] Running download-and-extract step. 07:02:29 INFO - Running pre-action listener: _resource_record_pre_action 07:02:29 INFO - Running main action method: download_and_extract 07:02:29 INFO - mkdir: /builds/slave/test/build/tests 07:02:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:02:29 INFO - trying https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 07:02:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 07:02:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 07:02:30 INFO - Downloaded 1287 bytes. 07:02:30 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 07:02:30 INFO - Using the following test package requirements: 07:02:30 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:02:30 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 07:02:30 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 07:02:30 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:02:30 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 07:02:30 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:02:30 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 07:02:30 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 07:02:30 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:02:30 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 07:02:30 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 07:02:30 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:02:30 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 07:02:30 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:02:30 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 07:02:30 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:02:30 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 07:02:30 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:02:30 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 07:02:30 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 07:02:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 07:02:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 07:02:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 07:02:32 INFO - Expected file size: 51393275 07:02:32 INFO - Obtained file size: 51393275 07:02:47 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 07:02:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 07:02:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 07:02:47 INFO - Fetch https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 07:02:49 INFO - Expected file size: 51393275 07:02:49 INFO - Obtained file size: 51393275 07:03:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 07:03:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 07:03:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 07:03:05 INFO - Expected file size: 14404449 07:03:05 INFO - Obtained file size: 14404449 07:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:06 INFO - trying https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:03:06 INFO - Downloading https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 07:03:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 07:03:07 INFO - Downloaded 59648807 bytes. 07:03:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:03:07 INFO - mkdir: /builds/slave/test/properties 07:03:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:03:07 INFO - Writing to file /builds/slave/test/properties/build_url 07:03:07 INFO - Contents: 07:03:07 INFO - build_url:https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:03:08 INFO - Running post-action listener: _resource_record_post_action 07:03:08 INFO - Running post-action listener: set_extra_try_arguments 07:03:08 INFO - [mozharness: 2017-05-26 14:03:08.513507Z] Finished download-and-extract step (success) 07:03:08 INFO - [mozharness: 2017-05-26 14:03:08.513631Z] Running populate-webroot step. 07:03:08 INFO - Running pre-action listener: _resource_record_pre_action 07:03:08 INFO - Running main action method: populate_webroot 07:03:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:03:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:03:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:03:08 INFO - '--tppagecycles', 07:03:08 INFO - '7'], 07:03:08 INFO - 'tests': ['tsvgm']}}, 07:03:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:03:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:03:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:03:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:03:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:03:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:03:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:03:08 INFO - 'talos_options': ['--disable-e10s'], 07:03:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:03:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:03:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:03:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:03:08 INFO - 'talos_options': ['--disable-e10s'], 07:03:08 INFO - 'tests': ['damp', 'tps']}, 07:03:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:03:08 INFO - 'tests': ['damp', 'tps']}, 07:03:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:03:08 INFO - 'tests': ['dromaeo_dom']}, 07:03:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:03:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:03:08 INFO - 'tests': ['basic_compositor_video']}, 07:03:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:03:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:03:08 INFO - 'tests': ['a11yr', 07:03:08 INFO - 'ts_paint', 07:03:08 INFO - 'tpaint', 07:03:08 INFO - 'sessionrestore', 07:03:08 INFO - 'sessionrestore_no_auto_restore', 07:03:08 INFO - 'tabpaint']}, 07:03:08 INFO - 'other-e10s': {'tests': ['a11yr', 07:03:08 INFO - 'ts_paint', 07:03:08 INFO - 'tpaint', 07:03:08 INFO - 'sessionrestore', 07:03:08 INFO - 'sessionrestore_no_auto_restore', 07:03:08 INFO - 'tabpaint']}, 07:03:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:03:08 INFO - 'ts_paint', 07:03:08 INFO - 'tpaint', 07:03:08 INFO - 'sessionrestore', 07:03:08 INFO - 'sessionrestore_no_auto_restore', 07:03:08 INFO - 'tabpaint']}, 07:03:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:03:08 INFO - 'ts_paint', 07:03:08 INFO - 'tpaint', 07:03:08 INFO - 'sessionrestore', 07:03:08 INFO - 'sessionrestore_no_auto_restore', 07:03:08 INFO - 'tabpaint']}, 07:03:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:03:08 INFO - 'tests': ['a11yr', 07:03:08 INFO - 'ts_paint', 07:03:08 INFO - 'tpaint', 07:03:08 INFO - 'sessionrestore', 07:03:08 INFO - 'sessionrestore_no_auto_restore', 07:03:08 INFO - 'tabpaint']}, 07:03:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:03:08 INFO - 'tests': ['a11yr', 07:03:08 INFO - 'ts_paint', 07:03:08 INFO - 'tpaint', 07:03:08 INFO - 'sessionrestore', 07:03:08 INFO - 'sessionrestore_no_auto_restore', 07:03:08 INFO - 'tabpaint']}, 07:03:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:03:08 INFO - 'tests': ['tsvgx', 07:03:08 INFO - 'tsvgr_opacity', 07:03:08 INFO - 'tart', 07:03:08 INFO - 'tscrollx', 07:03:08 INFO - 'cart', 07:03:08 INFO - 'tsvg_static']}, 07:03:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:03:08 INFO - 'tsvgr_opacity', 07:03:08 INFO - 'tart', 07:03:08 INFO - 'tscrollx', 07:03:08 INFO - 'cart', 07:03:08 INFO - 'tsvg_static']}, 07:03:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:03:08 INFO - 'talos_options': ['--disable-e10s'], 07:03:08 INFO - 'tests': ['tp5o']}, 07:03:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:03:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:03:08 INFO - 'talos_options': ['--disable-e10s', 07:03:08 INFO - '--xperf_path', 07:03:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:03:08 INFO - 'tests': ['tp5n']}, 07:03:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:03:08 INFO - 'talos_options': ['--xperf_path', 07:03:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:03:08 INFO - 'tests': ['tp5n']}}, 07:03:08 INFO - 'talos.zip': {'path': '', 07:03:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:03:08 INFO - Running post-action listener: _resource_record_post_action 07:03:08 INFO - [mozharness: 2017-05-26 14:03:08.533838Z] Finished populate-webroot step (success) 07:03:08 INFO - [mozharness: 2017-05-26 14:03:08.533959Z] Running create-virtualenv step. 07:03:08 INFO - Running pre-action listener: _resource_record_pre_action 07:03:08 INFO - Running main action method: create_virtualenv 07:03:08 INFO - Creating virtualenv /builds/slave/test/build/venv 07:03:08 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 07:03:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:03:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:03:08 INFO - Using real prefix '/tools/python27' 07:03:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:03:09 INFO - Installing distribute.............................................................................................................................................................................................done. 07:03:12 INFO - Installing pip.................done. 07:03:12 INFO - Return code: 0 07:03:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:03:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:03:12 INFO - Reading from file tmpfile_stdout 07:03:12 INFO - Output received: 07:03:12 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) 07:03:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:03:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03: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/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 0x10694cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9f25b58f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10672ad30>, '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 0x106651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10671fbe8>, '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.tFvcbk0W4q/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.1ns17MAjmE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 07:03:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 07:03:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:03:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFvcbk0W4q/Render', 07:03:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:12 INFO - 'HOME': '/Users/cltbld', 07:03:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:12 INFO - 'LOGNAME': 'cltbld', 07:03:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:12 INFO - 'MOZ_NO_REMOTE': '1', 07:03:12 INFO - 'NO_EM_RESTART': '1', 07:03:12 INFO - 'PAGER': '/bin/cat', 07:03:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:12 INFO - 'PWD': '/builds/slave/test', 07:03:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:12 INFO - 'SHELL': '/bin/bash', 07:03:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ns17MAjmE/Listeners', 07:03:12 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 07:03:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:12 INFO - 'USER': 'cltbld', 07:03:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:12 INFO - 'XPC_FLAGS': '0x0', 07:03:12 INFO - 'XPC_SERVICE_NAME': '0', 07:03:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:12 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 07:03:12 INFO - Cleaning up... 07:03:12 INFO - Return code: 0 07:03:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:03:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03: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/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 0x10694cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9f25b58f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10672ad30>, '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 0x106651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10671fbe8>, '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.tFvcbk0W4q/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.1ns17MAjmE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 07:03:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 07:03:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:03:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFvcbk0W4q/Render', 07:03:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:12 INFO - 'HOME': '/Users/cltbld', 07:03:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:12 INFO - 'LOGNAME': 'cltbld', 07:03:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:12 INFO - 'MOZ_NO_REMOTE': '1', 07:03:12 INFO - 'NO_EM_RESTART': '1', 07:03:12 INFO - 'PAGER': '/bin/cat', 07:03:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:12 INFO - 'PWD': '/builds/slave/test', 07:03:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:12 INFO - 'SHELL': '/bin/bash', 07:03:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ns17MAjmE/Listeners', 07:03:12 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 07:03:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:12 INFO - 'USER': 'cltbld', 07:03:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:12 INFO - 'XPC_FLAGS': '0x0', 07:03:12 INFO - 'XPC_SERVICE_NAME': '0', 07:03:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:13 INFO - Downloading/unpacking psutil>=3.1.1 07:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:03:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:03:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:03:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:03:14 INFO - Installing collected packages: psutil 07:03:14 INFO - Running setup.py install for psutil 07:03:15 INFO - building 'psutil._psutil_osx' extension 07:03:15 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 07:03:15 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 07:03:15 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 07:03:15 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 07:03:15 INFO - building 'psutil._psutil_posix' extension 07:03:15 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 07:03:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:03:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:03:15 INFO - ^ 07:03:15 INFO - 1 warning generated. 07:03:15 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 07:03:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:03:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:03:16 INFO - Successfully installed psutil 07:03:16 INFO - Cleaning up... 07:03:16 INFO - Return code: 0 07:03:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:03:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03: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/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 0x10694cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9f25b58f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10672ad30>, '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 0x106651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10671fbe8>, '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.tFvcbk0W4q/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.1ns17MAjmE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 07:03:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 07:03:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:03:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFvcbk0W4q/Render', 07:03:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:16 INFO - 'HOME': '/Users/cltbld', 07:03:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:16 INFO - 'LOGNAME': 'cltbld', 07:03:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:16 INFO - 'MOZ_NO_REMOTE': '1', 07:03:16 INFO - 'NO_EM_RESTART': '1', 07:03:16 INFO - 'PAGER': '/bin/cat', 07:03:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:16 INFO - 'PWD': '/builds/slave/test', 07:03:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:16 INFO - 'SHELL': '/bin/bash', 07:03:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ns17MAjmE/Listeners', 07:03:16 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 07:03:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:16 INFO - 'USER': 'cltbld', 07:03:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:16 INFO - 'XPC_FLAGS': '0x0', 07:03:16 INFO - 'XPC_SERVICE_NAME': '0', 07:03:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:03:16 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 07:03:16 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 07:03:16 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 07:03:16 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 07:03:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:03:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:03:17 INFO - Installing collected packages: mozsystemmonitor 07:03:17 INFO - Running setup.py install for mozsystemmonitor 07:03:18 INFO - Successfully installed mozsystemmonitor 07:03:18 INFO - Cleaning up... 07:03:18 INFO - Return code: 0 07:03:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10694cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9f25b58f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10672ad30>, '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 0x106651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10671fbe8>, '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.tFvcbk0W4q/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.1ns17MAjmE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 07:03:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 07:03:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:03:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFvcbk0W4q/Render', 07:03:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:18 INFO - 'HOME': '/Users/cltbld', 07:03:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:18 INFO - 'LOGNAME': 'cltbld', 07:03:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:18 INFO - 'MOZ_NO_REMOTE': '1', 07:03:18 INFO - 'NO_EM_RESTART': '1', 07:03:18 INFO - 'PAGER': '/bin/cat', 07:03:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:18 INFO - 'PWD': '/builds/slave/test', 07:03:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:18 INFO - 'SHELL': '/bin/bash', 07:03:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ns17MAjmE/Listeners', 07:03:18 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 07:03:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:18 INFO - 'USER': 'cltbld', 07:03:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:18 INFO - 'XPC_FLAGS': '0x0', 07:03:18 INFO - 'XPC_SERVICE_NAME': '0', 07:03:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:19 INFO - Downloading/unpacking jsonschema==2.5.1 07:03: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 07:03: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 07:03: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 07:03: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 07:03:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:03:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:03:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:03:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:03:21 INFO - Downloading functools32-3.2.3-2.tar.gz 07:03:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:03:21 INFO - warning: no files found matching '*.txt' 07:03:21 INFO - no previously-included directories found matching 'build' 07:03:21 INFO - no previously-included directories found matching 'dist' 07:03:21 INFO - no previously-included directories found matching '.git*' 07:03:21 INFO - Installing collected packages: jsonschema, functools32 07:03:21 INFO - Running setup.py install for functools32 07:03:21 INFO - warning: no files found matching '*.txt' 07:03:21 INFO - no previously-included directories found matching 'build' 07:03:21 INFO - no previously-included directories found matching 'dist' 07:03:21 INFO - no previously-included directories found matching '.git*' 07:03:21 INFO - Successfully installed jsonschema functools32 07:03:21 INFO - Cleaning up... 07:03:21 INFO - Return code: 0 07:03:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10694cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9f25b58f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10672ad30>, '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 0x106651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10671fbe8>, '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.tFvcbk0W4q/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.1ns17MAjmE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 07:03:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 07:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:03:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFvcbk0W4q/Render', 07:03:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:21 INFO - 'HOME': '/Users/cltbld', 07:03:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:21 INFO - 'LOGNAME': 'cltbld', 07:03:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:21 INFO - 'MOZ_NO_REMOTE': '1', 07:03:21 INFO - 'NO_EM_RESTART': '1', 07:03:21 INFO - 'PAGER': '/bin/cat', 07:03:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:21 INFO - 'PWD': '/builds/slave/test', 07:03:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:21 INFO - 'SHELL': '/bin/bash', 07:03:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ns17MAjmE/Listeners', 07:03:21 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 07:03:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:21 INFO - 'USER': 'cltbld', 07:03:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:21 INFO - 'XPC_FLAGS': '0x0', 07:03:21 INFO - 'XPC_SERVICE_NAME': '0', 07:03:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:03:21 INFO - Cleaning up... 07:03:21 INFO - Return code: 0 07:03:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10694cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9f25b58f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10672ad30>, '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 0x106651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10671fbe8>, '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.tFvcbk0W4q/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.1ns17MAjmE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 07:03:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 07:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:03:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFvcbk0W4q/Render', 07:03:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:21 INFO - 'HOME': '/Users/cltbld', 07:03:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:21 INFO - 'LOGNAME': 'cltbld', 07:03:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:21 INFO - 'MOZ_NO_REMOTE': '1', 07:03:21 INFO - 'NO_EM_RESTART': '1', 07:03:21 INFO - 'PAGER': '/bin/cat', 07:03:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:21 INFO - 'PWD': '/builds/slave/test', 07:03:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:21 INFO - 'SHELL': '/bin/bash', 07:03:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ns17MAjmE/Listeners', 07:03:21 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 07:03:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:21 INFO - 'USER': 'cltbld', 07:03:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:21 INFO - 'XPC_FLAGS': '0x0', 07:03:21 INFO - 'XPC_SERVICE_NAME': '0', 07:03:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:22 INFO - Downloading/unpacking blobuploader==1.2.4 07:03:22 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 07:03:22 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 07:03:22 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 07:03:22 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 07:03:23 INFO - Downloading blobuploader-1.2.4.tar.gz 07:03:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:03:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:03:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:03:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:03:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:03:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:03:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:03:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:03:24 INFO - Downloading docopt-0.6.1.tar.gz 07:03:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:03:24 INFO - Installing collected packages: blobuploader, requests, docopt 07:03:24 INFO - Running setup.py install for blobuploader 07:03:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:03:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:03:24 INFO - Running setup.py install for requests 07:03:25 INFO - Running setup.py install for docopt 07:03:25 INFO - Successfully installed blobuploader requests docopt 07:03:25 INFO - Cleaning up... 07:03:25 INFO - Return code: 0 07:03:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:03:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10694cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9f25b58f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10672ad30>, '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 0x106651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10671fbe8>, '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.tFvcbk0W4q/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.1ns17MAjmE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 07:03:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:03:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFvcbk0W4q/Render', 07:03:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:25 INFO - 'HOME': '/Users/cltbld', 07:03:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:25 INFO - 'LOGNAME': 'cltbld', 07:03:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:25 INFO - 'MOZ_NO_REMOTE': '1', 07:03:25 INFO - 'NO_EM_RESTART': '1', 07:03:25 INFO - 'PAGER': '/bin/cat', 07:03:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:25 INFO - 'PWD': '/builds/slave/test', 07:03:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:25 INFO - 'SHELL': '/bin/bash', 07:03:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ns17MAjmE/Listeners', 07:03:25 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 07:03:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:25 INFO - 'USER': 'cltbld', 07:03:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:25 INFO - 'XPC_FLAGS': '0x0', 07:03:25 INFO - 'XPC_SERVICE_NAME': '0', 07:03:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:03:25 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-d8LeEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:03:25 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-u0ywHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:03:25 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-BmFERg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:03:25 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-fHzBb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:03:26 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-PsbJRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:03:26 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-dS5tOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:03:26 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-Czo9jo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:03:26 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-SGoGm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:03:26 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-_t4wlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:03:26 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-NW3qPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:03:26 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-cEcdks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:03:26 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-uUMNev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:03:26 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-Q2FQ71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:03:26 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-KhDiGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:03:27 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-ZaqIuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:03:27 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-ma6swE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:03:27 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-Geox85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:03:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:03:27 INFO - Running setup.py install for manifestparser 07:03:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:03:27 INFO - Running setup.py install for mozcrash 07:03:27 INFO - Running setup.py install for mozdebug 07:03:27 INFO - Running setup.py install for mozdevice 07:03:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:03:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:03:27 INFO - Running setup.py install for mozfile 07:03:28 INFO - Running setup.py install for mozhttpd 07:03:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:03:28 INFO - Running setup.py install for mozinfo 07:03:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:03:28 INFO - Running setup.py install for mozInstall 07:03:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:03:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:03:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:03:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:03:28 INFO - Running setup.py install for mozleak 07:03:28 INFO - Running setup.py install for mozlog 07:03:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:03:28 INFO - Running setup.py install for moznetwork 07:03:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:03:28 INFO - Running setup.py install for mozprocess 07:03:29 INFO - Running setup.py install for mozprofile 07:03:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:03:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:03:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:03:29 INFO - Running setup.py install for mozrunner 07:03:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:03:29 INFO - Running setup.py install for mozscreenshot 07:03:29 INFO - Running setup.py install for moztest 07:03:29 INFO - Running setup.py install for mozversion 07:03:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:03:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:03:29 INFO - Cleaning up... 07:03:29 INFO - Return code: 0 07:03:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:03:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:29 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/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 0x10694cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9f25b58f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10672ad30>, '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 0x106651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10671fbe8>, '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.tFvcbk0W4q/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.1ns17MAjmE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 07:03:29 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:03:29 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:03:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFvcbk0W4q/Render', 07:03:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:29 INFO - 'HOME': '/Users/cltbld', 07:03:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:29 INFO - 'LOGNAME': 'cltbld', 07:03:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:29 INFO - 'MOZ_NO_REMOTE': '1', 07:03:29 INFO - 'NO_EM_RESTART': '1', 07:03:29 INFO - 'PAGER': '/bin/cat', 07:03:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:29 INFO - 'PWD': '/builds/slave/test', 07:03:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:29 INFO - 'SHELL': '/bin/bash', 07:03:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ns17MAjmE/Listeners', 07:03:29 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 07:03:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:29 INFO - 'USER': 'cltbld', 07:03:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:29 INFO - 'XPC_FLAGS': '0x0', 07:03:29 INFO - 'XPC_SERVICE_NAME': '0', 07:03:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:03:30 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-ZQyrhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:03:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:03:30 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-SGTvaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:03:30 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)) 07:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:03:30 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-32AIBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:03:30 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)) 07:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:03:30 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-nUsJin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:03:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:03:30 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-DQK7xt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:03:30 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)) 07:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:03:30 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-TCbZud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:03:30 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)) 07:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:03:30 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-QZGiTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:03:30 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)) 07:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:03:30 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-YMAEsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:03:31 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-gWgpqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:03:31 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-XgF_rI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:03:31 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)) 07:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:03:31 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-btd5ol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:03:31 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)) 07:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:03:31 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-OGOKu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:03:31 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)) 07:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:03:31 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-6wHi2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:03:31 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)) 07:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:03:31 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-AjtIdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:03:31 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)) 07:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:03:31 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-fYxDfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:03:31 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)) 07:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:03:31 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-8g8y5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:03:31 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)) 07:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:03:31 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-kv2zEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:03:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:03:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:03:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:03:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:03:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:03:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:03:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:03:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:03:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:03:33 INFO - Downloading blessings-1.6.tar.gz 07:03:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:03:33 INFO - Installing collected packages: blessings 07:03:33 INFO - Running setup.py install for blessings 07:03:33 INFO - Successfully installed blessings 07:03:33 INFO - Cleaning up... 07:03:33 INFO - Return code: 0 07:03:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:03:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:03:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:03:34 INFO - Reading from file tmpfile_stdout 07:03:34 INFO - Current package versions: 07:03:34 INFO - blessings == 1.6 07:03:34 INFO - blobuploader == 1.2.4 07:03:34 INFO - docopt == 0.6.1 07:03:34 INFO - functools32 == 3.2.3-2 07:03:34 INFO - jsonschema == 2.5.1 07:03:34 INFO - manifestparser == 1.1 07:03:34 INFO - mozInstall == 1.12 07:03:34 INFO - mozcrash == 1.0 07:03:34 INFO - mozdebug == 0.1 07:03:34 INFO - mozdevice == 0.49 07:03:34 INFO - mozfile == 1.2 07:03:34 INFO - mozhttpd == 0.7 07:03:34 INFO - mozinfo == 0.9 07:03:34 INFO - mozleak == 0.1 07:03:34 INFO - mozlog == 3.4 07:03:34 INFO - moznetwork == 0.27 07:03:34 INFO - mozprocess == 0.25 07:03:34 INFO - mozprofile == 0.28 07:03:34 INFO - mozrunner == 6.13 07:03:34 INFO - mozscreenshot == 0.1 07:03:34 INFO - mozsystemmonitor == 0.3 07:03:34 INFO - moztest == 0.8 07:03:34 INFO - mozversion == 1.4 07:03:34 INFO - psutil == 3.1.1 07:03:34 INFO - requests == 1.2.3 07:03:34 INFO - wsgiref == 0.1.2 07:03:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:03:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:34 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/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 0x10694cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9f25b58f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10672ad30>, '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 0x106651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10671fbe8>, '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.tFvcbk0W4q/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.1ns17MAjmE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 07:03:34 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:03:34 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:03:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFvcbk0W4q/Render', 07:03:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:34 INFO - 'HOME': '/Users/cltbld', 07:03:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:34 INFO - 'LOGNAME': 'cltbld', 07:03:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:34 INFO - 'MOZ_NO_REMOTE': '1', 07:03:34 INFO - 'NO_EM_RESTART': '1', 07:03:34 INFO - 'PAGER': '/bin/cat', 07:03:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:34 INFO - 'PWD': '/builds/slave/test', 07:03:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:34 INFO - 'SHELL': '/bin/bash', 07:03:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ns17MAjmE/Listeners', 07:03:34 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 07:03:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:34 INFO - 'USER': 'cltbld', 07:03:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:34 INFO - 'XPC_FLAGS': '0x0', 07:03:34 INFO - 'XPC_SERVICE_NAME': '0', 07:03:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:34 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)) 07:03:34 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)) 07:03:34 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)) 07:03:34 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)) 07:03:34 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)) 07:03:34 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)) 07:03:34 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)) 07:03:34 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)) 07:03:34 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)) 07:03:34 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)) 07:03:34 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)) 07:03:34 INFO - Cleaning up... 07:03:34 INFO - Return code: 0 07:03:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:03:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:34 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/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 0x10694cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10694f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9f25b58f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10672ad30>, '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 0x106651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10671fbe8>, '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.tFvcbk0W4q/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.1ns17MAjmE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 07:03:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:03:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:03:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFvcbk0W4q/Render', 07:03:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:34 INFO - 'HOME': '/Users/cltbld', 07:03:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:34 INFO - 'LOGNAME': 'cltbld', 07:03:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:34 INFO - 'MOZ_NO_REMOTE': '1', 07:03:34 INFO - 'NO_EM_RESTART': '1', 07:03:34 INFO - 'PAGER': '/bin/cat', 07:03:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:34 INFO - 'PWD': '/builds/slave/test', 07:03:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:34 INFO - 'SHELL': '/bin/bash', 07:03:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ns17MAjmE/Listeners', 07:03:34 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 07:03:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:34 INFO - 'USER': 'cltbld', 07:03:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:34 INFO - 'XPC_FLAGS': '0x0', 07:03:34 INFO - 'XPC_SERVICE_NAME': '0', 07:03:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:03:34 INFO - Cleaning up... 07:03:34 INFO - Return code: 0 07:03:34 INFO - Running post-action listener: _resource_record_post_action 07:03:34 INFO - Running post-action listener: _start_resource_monitoring 07:03:34 INFO - Starting resource monitoring. 07:03:34 INFO - [mozharness: 2017-05-26 14:03:34.682213Z] Finished create-virtualenv step (success) 07:03:34 INFO - [mozharness: 2017-05-26 14:03:34.683158Z] Running install step. 07:03:34 INFO - Running pre-action listener: _resource_record_pre_action 07:03:34 INFO - Running main action method: install 07:03:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:03:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:03:34 INFO - Reading from file tmpfile_stdout 07:03:34 INFO - Detecting whether we're running mozinstall >=1.0... 07:03:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:03:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:03:35 INFO - Reading from file tmpfile_stdout 07:03:35 INFO - Output received: 07:03:35 INFO - Usage: mozinstall [options] installer 07:03:35 INFO - Options: 07:03:35 INFO - -h, --help show this help message and exit 07:03:35 INFO - -d DEST, --destination=DEST 07:03:35 INFO - Directory to install application into. [default: 07:03:35 INFO - "/builds/slave/test"] 07:03:35 INFO - --app=APP Application being installed. [default: firefox] 07:03:35 INFO - mkdir: /builds/slave/test/build/application 07:03:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:03:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 07:03:50 INFO - Reading from file tmpfile_stdout 07:03:50 INFO - Output received: 07:03:50 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 07:03:50 INFO - Running post-action listener: _resource_record_post_action 07:03:50 INFO - [mozharness: 2017-05-26 14:03:50.021885Z] Finished install step (success) 07:03:50 INFO - [mozharness: 2017-05-26 14:03:50.022037Z] Running run-tests step. 07:03:50 INFO - Running pre-action listener: _resource_record_pre_action 07:03:50 INFO - Running main action method: run_tests 07:03:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:03:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:03:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:03:50 INFO - 2017-05-26 07:03:50.036 screenresolution[1627:7460] starting screenresolution argv=screenresolution get 07:03:50 INFO - 2017-05-26 07:03:50.039 screenresolution[1627:7460] Display 0: 1600x1200x32@60 07:03:50 INFO - 2017-05-26 07:03:50.045 screenresolution[1628:7462] starting screenresolution argv=screenresolution list 07:03:50 INFO - Available Modes on Display 0 07:03:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:03:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:03:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:03:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:03:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:03:50 INFO - Intel Iris: 07:03:50 INFO - Chipset Model: Intel Iris 07:03:50 INFO - Type: GPU 07:03:50 INFO - Bus: Built-In 07:03:50 INFO - VRAM (Dynamic, Max): 1536 MB 07:03:50 INFO - Vendor: Intel (0x8086) 07:03:50 INFO - Device ID: 0x0a2e 07:03:50 INFO - Revision ID: 0x0009 07:03:50 INFO - Displays: 07:03:50 INFO - Display: 07:03:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:03:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:03:50 INFO - Display Serial Number: 1600x1200 60 07:03:50 INFO - Main Display: Yes 07:03:50 INFO - Mirror: Off 07:03:50 INFO - Online: Yes 07:03:50 INFO - Rotation: Supported 07:03:50 INFO - Return code: 0 07:03:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:03:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:03:50 INFO - Python 2.7.3 07:03:50 INFO - Return code: 0 07:03:50 INFO - grabbing minidump binary from tooltool 07:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 07:03: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 0x10672ad30>, '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 0x106651db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10671fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:03: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 07:03: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 07:03: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 07:03:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:03:50 INFO - Return code: 0 07:03:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:03:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:03:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:03:50 INFO - ENV: RUST_BACKTRACE is now 1 07:03:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:03:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:03:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0267', '--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 07:03:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0267 --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 07:03:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFvcbk0W4q/Render', 07:03:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:50 INFO - 'HOME': '/Users/cltbld', 07:03:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:50 INFO - 'LOGNAME': 'cltbld', 07:03:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:03:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:50 INFO - 'MOZ_NO_REMOTE': '1', 07:03:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:03:50 INFO - 'NO_EM_RESTART': '1', 07:03:50 INFO - 'PAGER': '/bin/cat', 07:03:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:50 INFO - 'PWD': '/builds/slave/test', 07:03:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:03:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:50 INFO - 'RUST_BACKTRACE': '1', 07:03:50 INFO - 'SHELL': '/bin/bash', 07:03:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ns17MAjmE/Listeners', 07:03:50 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 07:03:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:50 INFO - 'USER': 'cltbld', 07:03:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:50 INFO - 'XPC_FLAGS': '0x0', 07:03:50 INFO - 'XPC_SERVICE_NAME': '0', 07:03:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0267', '--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 07:03:50 INFO - mozversion application_buildid: 20170526053516 07:03:50 INFO - mozversion application_changeset: 83e94c015805483d998deddc86f3a541edba50d7 07:03:50 INFO - mozversion application_display_name: Firefox 07:03:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:03:50 INFO - mozversion application_name: Firefox 07:03:50 INFO - mozversion application_remotingname: firefox 07:03:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:03:50 INFO - mozversion application_vendor: Mozilla 07:03:50 INFO - mozversion application_version: 54.0 07:03:50 INFO - mozversion platform_buildid: 20170526053516 07:03:50 INFO - mozversion platform_changeset: 83e94c015805483d998deddc86f3a541edba50d7 07:03:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:03:50 INFO - mozversion platform_version: 54.0 07:03:50 INFO - using testdate: 1495807430 07:03:50 INFO - actual date: 1495807430 07:03:50 INFO - starting webserver on 'localhost:49224' 07:03:50 INFO - SUITE-START | Running 2 tests 07:03:50 INFO - TEST-START | dromaeo_css 07:03:50 INFO - operating with platform_type : mac_ 07:03:50 INFO - Initialising browser for dromaeo_css test... 07:03:50 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/tmpHxrxq1/profile http://localhost:49224/getInfo.html) 07:03:53 INFO - PROCESS | 1638 | 2017-05-26 07:03:53.532 plugin-container[1641:7631] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 07:03:53 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:03:53 INFO - PROCESS | 1638 | 2017-05-26 07:03:53.532 plugin-container[1641:7631] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 07:03:53 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:03:53 INFO - PROCESS | 1638 | 2017-05-26 07:03:53.532 plugin-container[1641:7631] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:03:53 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 07:03:53 INFO - PROCESS | 1638 | colorDepth:24 07:03:53 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 07:03:53 INFO - PROCESS | 1638 | __metrics 07:03:53 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 07:03:54 INFO - TEST-INFO | 1638: exit 0 07:03:54 INFO - Browser initialized. 07:03:54 INFO - Running cycle 1/1 for dromaeo_css test... 07:03:54 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/tmpHxrxq1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:03:56 INFO - PROCESS | 1642 | 2017-05-26 07:03:56.469 plugin-container[1643:7776] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424b, name = 'com.apple.tsm.portname' 07:03:56 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:03:56 INFO - PROCESS | 1642 | 2017-05-26 07:03:56.469 plugin-container[1643:7776] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 07:03:56 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:03:56 INFO - PROCESS | 1642 | 2017-05-26 07:03:56.469 plugin-container[1643:7776] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:04:06 INFO - PROCESS | 1642 | RSS: Main: 174350336 07:04:06 INFO - PROCESS | 1642 | 07:07:10 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 07:07:11 INFO - PROCESS | 1642 | RSS: Main: 189329408 07:07:11 INFO - PROCESS | 1642 | 07:10:14 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 07:10:14 INFO - PROCESS | 1642 | RSS: Main: 203902976 07:10:14 INFO - PROCESS | 1642 | 07:13:16 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 07:13:17 INFO - PROCESS | 1642 | RSS: Main: 208601088 07:13:17 INFO - PROCESS | 1642 | 07:16:19 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 07:16:19 INFO - PROCESS | 1642 | RSS: Main: 211677184 07:16:19 INFO - PROCESS | 1642 | 07:19:23 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 07:19:23 INFO - PROCESS | 1642 | RSS: Main: 214605824 07:19:23 INFO - PROCESS | 1642 | 07:22:26 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 07:22:26 INFO - PROCESS | 1642 | RSS: Main: 212873216 07:22:26 INFO - PROCESS | 1642 | 07:22:26 INFO - PROCESS | 1642 | __start_tp_report 07:22:26 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 07:22:26 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 07:22:26 INFO - PROCESS | 1642 | |i|pagename|runs| 07:22:26 INFO - PROCESS | 1642 | |0;dojo.html;3624.76439031463;3672.008557689424;3695.6304369563045;3707.277080969212;3708.388115960867;6195;6223.844403889905;6238.251409830816;6239.095331176976;6275.152854364659;6180.153830028294;6243.937560624392;6264.8120556383265;6269.780557680482;6298.527610429224;4277.3156294992805;5567.582431317667;5604.131359639263;5621.241132447119;5635.464630860061;4661.8834529136875;5380.166074258496;5690.630109042899;5717.8570535736735;5768.250127483421;5458.781648734046;5495.53287970521;5648.096304591266;5706.058500347458;5716.371199168088;4301.204277208726;5576.637518561281;5599.8880022399735;5618.410066942994;5645.887082258373;5519.337679478448;5637.746301416446;5639.323281206241;5665.745041473144;5671.177679236487;4653.6044436222965;6249.531285153586;6268.15379923709;6277.591956522857;6291.905621415683;6343.175387199681;6393.904091438632;6401.135846660734;6553.2136143663065;6639.302873198341;6702.262751097375;6739.258681545026;6760.459163266927;6764.154480689914;6793.626350550718;6259.593126446766;6318.589291696071;6325.746970121144;6332.841678958063;6337.397947195009;6578.638174900427;6664.333566643297;6801.557898736566;6923.861522769516;6950.756723514653;6383.265924418752;6440.259370172396;6479.5788273762055;6580;6581.86836263272;6771.424428923597;6772;6772.661366931634;6777.966110169417;6807.4894382921475;6588.068309602277;6610.206775186912;6676.232233293136;6693.096431981718;6693.330666933268;5090.592752579785;6244.03217501279;6528.314526974695;6567.376099270562;6601.3728695773825;5218.530332270038;5279.340082489689;5323.707196104174;5368.355797304349;5425.403205647375;4690.108879312919;4756.025014871943;4774.1883879739835;4779.0680817241;4784.40194975628;5239.240310155006;6237.563370564108;6321.956877115316;6323.430891219812;6328.651924144124;6407.967960160169;6450.4839612830865;6642.43539299165;6737.966310168418;6764.966175169191;6231.4391704745885;6288.937110628926;6307.337729538424;6333.429991300805;6336.429721325103;5955.37468565803;5971.4327138921735;5971.9701401493585;5973.940260597338;6002.339742628307;6569.967150164219;6699.995000749732;6918.515703900679;6978.337057979484;6991.196012458632;6625.17185351831;6655.134832471747;6659.900101498578;6679.365460281266;6728.226253980659;5098.413682426569;5836.678982655994;6999.12510936133;7004.929950700428;7021.964890175721;5327;6634.303398143318;6661.733530658719;6667.299933506912;6681.2650608434005;6263.3736626337;6380.617162970237;6544.181977252843;6576.309487503743;6658.134442522635;5411.945880541144;6542.83642908912;6562.376574225441;6575.934240657723;6576.506761992774;6518.217813862367;6579.901301480386;6581.3747693969;6582.637954912334;6600.966995164993;6087.208662873798;6262.937370626418;6299.622022678654;6310.5267104968225;6330.113784070326;6544.8363790905605;6559.868802624016;6562.737490500326;6582.210134783857;6605.5376123670885;6212.378762123752;6215.81352559424;6356.841078973062;6388;6412.909805333198 07:22:26 INFO - PROCESS | 1642 | |1;ext.html;782.3584660578324;820.5897051474263;844.0462277626282;983.1692220074035;986.1863962231157;38970.61029389705;40073.99815004626;41329;41541.37687934683;41671.54149604764;39682.40476392856;39926.401103983466;40034.799826000904;40108.398374024415;40135.59864401355;8236.711715089974;8392.622331995044;11151.498182581763;11445.942770286138;11679.5912143075;11258.211925165242;11329;11330.320180789138;11332.263402878827;11333.886661133407;11267.774644507146;11316.660500185008;11327.150463715214;11334.659960201207;11339.489722962486;11272.943635281812;11279.887201128007;11298.548058077626;11301.321920684784;11311.660650180507;11074.446277686084;11081.889181108247;11168.832467512993;11175.888241117647;11176.776464470666;34217.48673769896;34463;34492;34532.82733586316;34533.13667158316;3906;3944.7435916665327;3950.7432016919097;3959.762414255154;3964.960350396517;24729;24843.751562484325;25731.228063158134;26181.214563563124;27113.457730845468;3148.5592017117624;3174.619045714506;3188.0754581171664;3225.0808519572042;3248.96751032489;308985;309854;356404;363030;369991.9999999973;42605;42903.35644965328;42911;42957.785211073744;43018.924526886454;44751.77624111858;45217.869553260774;45423.31865022027;45462;45556.77221613937;11857.762844743229;12514.937425312997;14229.92885035589;14399.136051836924;14416.85583144176;12479.875201248053;12635.93682031584;12652.367381630882;12680.492780288869;12709.428075736614;11817.881821181678;11834.822477662841;11839;11878.168528202943;11910.225835320676;4907.312976183337;5747.942520574741;5754.395788442153;5777.797777077866;5787.681677507778;956.5982287439292;979.9804003920025;980.8185485685028;988.9851652225223;995.2833959549113;28437.289067773472;28730.425391492052;28992.565111523345;29160.27099322534;29163.562546561818;929.9488528131018;1058.714147180257;1071.6892101290694;1098.5715570927346;1153.6942710181809;13456.46174153023;14146.575602731935;14457.78313325301;14696.706065878836;14768.335424905905;484515;484544;484556;486698;487390;46513;47264.291035634495;47268;47377;47537.52462475331;1483.458537633756;1491.2841835919037;1508.102678906049;1515.5983664328962;1524.1312451902531;25353.366165846;26458.07396741105;26773.062942796907;27014.864925675247;27104.728952710222;28997;45071.647850564535;45908.54091459043;47677;48476.5152348472;484419;486566;486661;488257;489160;27139;27588.17235482939;32420.027399178063;32938.341233174724;33062.66937330596;39618.80190599144;39718.404223936086;40337;40385.1922961545;41163.38254926239;27913.860430697718;28139;28595.714042859305;28988;29401.41197176002;24796.876015619808;28256.86972521076;29004.7099529002;29083.418331632827;29799.404011920073;33603;34435;34903.476447852794;34983.300333992665;35251.82374088113;28894.855525722236;29168.124956251348;29208.41583168282;29670.961516346822;30064.09807705685;23644.8817755917;23746.931388087232;23818.16636417717;24191.758082419656;24224.394390140387 07:22:26 INFO - PROCESS | 1642 | |2;jquery.html;420.77698819625607;438.0887753472777;440.5044325134224;446.18571107728394;446.535602972908;21356.145754169833;21670;22068;22211.777882221173;22812.543749125027;18815.529611759714;22297.554048919053;22796.88601556994;22955.770442295572;23052.769472305274;5844.94790937631;6856.965715171417;7120.750773722921;7136.678849451787;7149.249328820477;6875.174979002527;7024.683889224997;7037.542559733626;7045.788626341218;7056.470764692643;6746.797596072101;6985.301469853025;7036.542624729376;7037;7043.894341584854;5702.7433765480655;6827.931720682779;6924.792256232296;6957.199922008957;7012.964935175342;6769.796906092776;6797.218319893226;6807.863842723167;6829.112215411965;6830.248672646005;24802.883870225884;25765.098221562155;26447.47105057888;26669.46661066787;26918.730812691818;2478.8264821462503;2998.175501737018;3040.1791516290477;3055.6791536888527;3062.7549796016488;16197.433089841918;16286.26711797972;16404.6719065618;16581.91709041447;16598.834011659972;2169.4684802223555;2173.9565208695735;2208.2271205078255;2249.6737972993824;2304.4238940264936;170033;170773;178065;178266.21733782627;181232;14498;19682.311119111047;20086.69869951952;20567;20840.06219720086;29285;29847.552286715283;30207.244818879266;30208.697913020587;30246.243843903638;10350.896491034993;10520.736981575368;10734.946325268324;10750.97865702757;10819.242653014371;9072.455652660861;9107.043760405195;9131.452112873249;9252.49111298885;9262.351635385594;9158.633654653873;9335.859962100574;9367.672131475365;9379.531023448937;9418.858717119248;4377.9781101094295;4441.268619798865;4446.4886538047895;4464.910701785946;4473;991.7949691125174;1008.2992320337295;1036.7926414717087;1039.298473530364;1070.250824422907;18346.266149353876;20844.687329690067;21127.620598412206;21168.682469762967;21190;1032.4528000159928;1037.1391744851755;1052.610534102387;1058.878229003659;1082.7617924056694;10207.846882296772;10414.8619684287;10832.350058996486;10917.126629869592;10969.23215374932;764273;780918;790193;801001;805001;166619.33380666038;169573.3042669582;169992;170612.146939267;172104;1242.7576622558515;1279.5777393460137;1293.0366876676935;1303.6089173247988;1304.3543445994103;18149.27402903869;18683;18781.718274226172;18951.43145705631;18993.14530846143;66566;66623.66752664822;66760;67499.66250168718;68821.65589172022;80333.5983320103;97289;99547;99662;100339.49830250801;11294.378809165575;11454.312741235553;11602.419879006186;11837.704057398634;11896.048316134862;12569.183003104768;12593.622191334274;12736.23582585048;12891.904188144114;12949.935250323688;10768.892311076788;10808.135349171891;10984.06635435997;11075.501602427748;11483.655490335303;10188.643397481053;10371.962803719567;10425.53085111187;10437.02692641767;10688.038076573146;11092.556297748;11754.23597466162;11958.983486403753;11978.820317695416;12075;11410.201285909907;11579.478923448634;11676.591319303783;11913.880861191625;11981.64055078349;10860.945695271788;11146.721331966766;11154.944225278821;11362.886371136514;11465.656030319104 07:22:26 INFO - PROCESS | 1642 | |3;mootools.html;1538.6922615476904;1652.876034297428;1672.665466906619;1689.45937300064;1690.8140104588485;10985.945070274647;11646.24299420538;11663.883361166396;11689.59086431975;12068;11647.941760291187;11759.823602645945;11826.58606948757;11827.408629568508;11849.46677399517;2484.8136389770793;2504.524140413325;2507.3982244261338;2518.8110891683104;2519.886605102775;2451.6445115458337;2467.198160597804;2477.6283557466345;2480.4295012147218;2490.5392502387117;2417.3352328109822;2441.4628781668002;2443.7067551893797;2445.2297526279253;2463.1132792194794;2360.6931098957198;2362.5865473542062;2370.7036620422446;2401.6637670726113;2435.1355268879647;2469.2098528470897;2475.170817776047;2485.8135639827087;2487.502499500089;2498.375406148463;11485.48315325804;11662.241954272946;11713.824292635618;11720.941395293054;11756.823647645208;5299.496547827989;5958.880822383528;6123.173371594867;6134.570580059398;6144.631322120687;3682.9447558286647;3756.1924186299802;3792.50697409335;3829.8659546916;3869.187470631171;1288.3815768431205;1922.8173323534243;1928.623918335925;1959.1340766821224;1987.552800619849;14474.348654310581;14832.851671483362;15429.22853857314;16136.838631613651;16255.105969171691;4819.108464933928;4834.057358814997;4983.626228032912;4991.401031876198;5007.24891266313;12129.87870121305;12245.08161887862;12334.568290109983;12345.888870001743;12347.950424214043;6099.176611157525;6100.054491553817;6102.7558897644485;6135.141080248769;6138.14066030756;5222.7127507987425;5347.625666203407;5401.108817045144;5589.105743081169;5656.745446454919;4220.6412454941055;4249.723767955073;4296.957030429656;4348.7390756554705;4352.107817897323;1037.725002874239;1170.0639488409238;1185.4013723069795;1193.0574845871838;1215.4226742297515;980.568549838069;999.6554634017235;1012.9898701012895;1024.7130803375067;1025.2874252394663;6874.312568743186;6923.6884340204815;7014.298570143047;7081.575105493687;7084.114485689289;1027.445179603021;1050.9857987042387;1052.3422860712055;1063.8776540697918;1094.584058057933;4617.907641847144;4701.48283688794;4714.22215334466;4744.0037592105355;4826.758662066883;13360.732785344433;13567.253801041037;14108.224047677271;14511.637209069857;14571.85428145705;14034.017618766793;14046.648833779238;14287.214203218922;14287.64280892986;14325.713485730226;8803.339749518811;10391.428471434143;10628.574857005788;10661.573537058586;10777.515011824487;5968.612040217372;6939.410150137093;6968.477364197604;7302.233265507258;7326.084239470067;11717.824232636347;11825.46785394642;12207.328596927255;12324.198927069712;12723.23660580368;12882.67793305175;12980.610581682564;13063;13113.868861311264;13270.270135142273;9430.434173949585;9694.854577181206;9727.513624318613;9809;9839.852402214116;10567.78864422692;10589.841152382874;10763.138948883914;10824.079953204066;10860.782784344427;9266.907330926873;9273.165415112673;9304.72085837426;9319.20786733108;9437.669681561112;8991.28069754405;9018.008019118022;9037.231835293811;9084.954575227082;9130.589123489326;8152;8331.708390206313;8355.456895301786;8363.330933525182;8403.033651129954;5366.892662146814;5377.300950876361;6018.470168737978;6048.183495228088;6082.90875636857;4559.658025648156;5630.071038278638;5776.133579963039;5782.942170578409;5925.111233315038 07:22:26 INFO - PROCESS | 1642 | |4;prototype.html;325.51660783736133;387.893329334433;392.24688597892043;394.67439362525914;398.82441282802307;4775.379200703904;5183.740812959356;5351.545118664913;5515.255440515529;5521.751521181546;4989.975050124754;5095.235714642806;5337.733113344327;5350.384705758829;5395.541378982782;3726.7577607455564;4096.877093687194;4316.848910288133;4380.671449641273;4413.374042444741;4388.89027774305;4434.778261086949;4437.378766972626;4444.844430444935;4446.733196008234;4219.620234178923;4369.235383807837;4420.469543654766;4429.645628349725;4434.645228381738;4152.688548358885;4334.869953901404;4374.803133858984;4389.824407023715;4420;4059.6549293310104;4060.573639767841;4091.181763647253;4093.63157315843;4123.628873401408;4316.546762589915;4565.539027511198;4566.036604260167;4822.683698090224;5222.738863056832;4217.768022758747;4452.443444569429;4642;5073.771680274396;5169.663971841818;5149.970005998778;5377.623566350357;5503.834884953458;5602.579806514527;5812.157237200625;4230.132822771325;4534.752942940685;4558.014500502177;4613.561711637389;4631.305304204363;5200.167973124282;5380.892382152374;6089.269287685462;6113.786017489411;6160.599561028519;3983.2830090583393;4191.245575796386;4205.3902184183735;4553.681242313072;4751.740788691;4058.5332686741403;4531;4783.784729687103;4842.201036828884;4844.4186697596515;4363.80362883671;4494.07871386365;4818.831340903052;5150.562202212779;5169.741512924339;5312.698388894668;5576.442355764391;5696.772129114872;5774.220480235199;5815.389384114607;1618.902865828054;1621.9270132844051;1623.7402015677435;1629.274972637165;1641.5157528529112;1165.2658824940233;1199.9700007499882;1216.7019080325376;1234.3026190202686;1238.585074000202;4659.743714095689;4875.293082503017;4887.755612219375;4982.953579748293;5395.81114660985;4958.752062396866;5442.510174084351;5622.437756224426;5991.4907232884825;5998.760049598054;4528.003839155381;4877.634262406532;5260.658057226268;5394.91907621386;5410.107332290128;2757.4485102979484;2760.674876059503;3113.8634398444856;3123.4065527550147;3125.3279495469815;5771.364040654361;5929.347771745104;5983.461488466059;6193.597416167935;6225.657588832658;5972.970135149297;6082.817515474543;6188.628682279078;6194.469152582985;6208.348123447064;5534.529564987021;6066.42273009012;6194.845128871814;6242.28213755424;6245.5628106033055;5957.285125784933;5986.640801551921;6014.609050411709;6049.727762250797;6152.107944347955;5560.471755183251;5679.346875109415;5944.740998420572;5972.4770183602905;5973.731182096729;5454.645448045864;6264.40257734293;6344.746210151542;6376.840578985377;6493.577917435352;4549.385832912514;4603.953960460352;4708.681569160641;4748.358971538798;4749.786259618257;4983.5763960063805;5223.608229382888;5863.00328944072;6000.939990600038;6036.336003039574;5119.539241468286;5150.91830715539;5843.649381036981;5851.64890106595;5885.823425297248;4955.752212389294;5316.680999140064;5499.230107784993;5728.448362015577;5791.739371728367;5533.474319939599;5675.602707810413;5725.541956643375;5736.6558006519745;5790.536757059509;5619.780186826099;5640.294963129609;5645.40723224072;5892.911606325994;5937.970310148422;5014.97193075426;5364.275822763877;5378.515933565998;5678.74445649955;5710.971445142748 07:22:26 INFO - PROCESS | 1642 | |5;yui.html;745.3776096959039;761.8742907420273;808.4583329169456;863.5552690364464;889.1641856654742;16375.590610234734;17611.471655850313;17617.82382176178;17639;17688.115594220286;16149.83850161498;16261.9429737067;16346.591335216626;16363.918180409111;16400.179991000463;974.7027156717216;987.5161171026217;988.7676396046924;991.5438898106879;992.5384696116305;1014.371089924248;1015.629295307215;1024.4672770159514;1026.1072866606025;1027.1115485105404;1049.9947500262488;1051.4742628685658;1055.6199768083484;1068.1401471815186;1068.2415485005656;1063.3991794636083;1063.5107850388792;1063.9893601064045;1063.9946800266027;1064.09551880901;787.0476723164977;787.0987719061642;787.5196130360475;788.1606089514648;788.8934993775881;17551.473455796346;17842.46472605824;17850.375236866777;18265.543361415937;18527.073646317764;14723.631909202355;14935.402583896632;14963.728083112948;15001.249937503082;15190.392384304614;11076.2365191521;11101.888981110167;11146.331220126818;11336.943315283454;11566.710832229177;3428.9485657714904;3762.5484941806885;3908.667763240128;4011.3782363733667;4064.1668457966343;90688;94191;111447;112127.87872121256;113004.43497782541;10603.522841472151;11549.826752598718;11553.36456494884;11657.242279251821;11704.648860534198;24094.51810963771;24127.15554955603;24231.39421514441;24290.14984475535;24300.27099187027;7673.731419400294;7722.266384693445;7887.9605601971625;7895.565743884142;8048.758537243892;3884.6698030667144;4644.465886423037;4651.418572678415;4660.790264438108;4689.20283551797;3498.2478767065204;3630.909227269339;3688.704903607705;3698.5007024051947;3702.204026134394;595.2499850188793;677.0419855903841;678.0541145102554;680.2449281297755;693.9791806245821;691.94810389221;703.2967032967033;703.4126504368807;726.5168662839153;746.3544034410196;10508.264421490423;10656.573737050587;10670.466476676134;11163.553457861757;11166.665000050012;649.8667773106501;714.5962531169872;736.14607055815;749.962501874915;758.4993904023318;4983.9252411213865;5214.809223985529;5239.1093514102695;5244.239585260192;5290.68255904647;107017;108122;110404.34393484105;110493;110573.89426105635;84696.15303847006;96733;97460.02539974652;99803;100133.49933250288;64870.67564662241;68819.55902204869;83162;83444.58277708573;83623.16376836154;8226;9065.637374504946;9662.08210220028;9867.40795552269;10141.645042423776;45426;47426.05147897092;51054.744726276134;54789.178162326796;55818.72090639521;69533.65233173802;69991.60016799533;71149;71471.21321966992;72610.27389726035;11155.442227888736;11283.717907052061;11577;11621;11666.76666466683;11434.37110958872;11862.525498979945;11968.281903085843;11988.880111199125;11990.220635658654;12249.510019599473;12610.180338277982;12640.115191936844;12857.421416036113;12939.158954667917;12344.75310493803;12681.682957926125;12786.552470663482;12937.547185848827;13307.26810025458;11461.197716159788;11940.223885447418;12094.818577721166;12355.876441235472;12484.625461236177;9191.540422978958;9548.57031433601;9645.065171369262;9818.263630227904;10245.590176392861;9214.723558293263;10216.846747298649;10460.163186945178;10754.569817207537;10763 07:22:26 INFO - PROCESS | 1642 | __end_tp_report 07:22:26 INFO - PROCESS | 1642 | __start_cc_report 07:22:26 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,392 07:22:26 INFO - PROCESS | 1642 | __end_cc_report 07:22:26 INFO - PROCESS | 1642 | __startTimestamp1495808546587__endTimestamp 07:22:26 INFO - PROCESS | 1642 | 07:22:26 INFO - PROCESS | 1642 | ------- Summary: start ------- 07:22:26 INFO - PROCESS | 1642 | Number of tests: 6 07:22:26 INFO - PROCESS | 1642 | 07:22:26 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:6115.13 Median:6325.75 stddev:727.40 (11.5%) stddev-sans-first:702.90 07:22:26 INFO - PROCESS | 1642 | Values: 3624.8 3672.0 3695.6 3707.3 3708.4 6195.0 6223.8 6238.3 6239.1 6275.2 6180.2 6243.9 6264.8 6269.8 6298.5 4277.3 5567.6 5604.1 5621.2 5635.5 4661.9 5380.2 5690.6 5717.9 5768.3 5458.8 5495.5 5648.1 5706.1 5716.4 4301.2 5576.6 5599.9 5618.4 5645.9 5519.3 5637.7 5639.3 5665.7 5671.2 4653.6 6249.5 6268.2 6277.6 6291.9 6343.2 6393.9 6401.1 6553.2 6639.3 6702.3 6739.3 6760.5 6764.2 6793.6 6259.6 6318.6 6325.7 6332.8 6337.4 6578.6 6664.3 6801.6 6923.9 6950.8 6383.3 6440.3 6479.6 6580.0 6581.9 6771.4 6772.0 6772.7 6778.0 6807.5 6588.1 6610.2 6676.2 6693.1 6693.3 5090.6 6244.0 6528.3 6567.4 6601.4 5218.5 5279.3 5323.7 5368.4 5425.4 4690.1 4756.0 4774.2 4779.1 4784.4 5239.2 6237.6 6322.0 6323.4 6328.7 6408.0 6450.5 6642.4 6738.0 6765.0 6231.4 6288.9 6307.3 6333.4 6336.4 5955.4 5971.4 5972.0 5973.9 6002.3 6570.0 6700.0 6918.5 6978.3 6991.2 6625.2 6655.1 6659.9 6679.4 6728.2 5098.4 5836.7 6999.1 7004.9 7022.0 5327.0 6634.3 6661.7 6667.3 6681.3 6263.4 6380.6 6544.2 6576.3 6658.1 5411.9 6542.8 6562.4 6575.9 6576.5 6518.2 6579.9 6581.4 6582.6 6601.0 6087.2 6262.9 6299.6 6310.5 6330.1 6544.8 6559.9 6562.7 6582.2 6605.5 6212.4 6215.8 6356.8 6388.0 6412.9 07:22:26 INFO - PROCESS | 1642 | 07:22:26 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:56372.38 Median:309419.50 stddev:118042.28 (38.1%) stddev-sans-first:118299.63 07:22:26 INFO - PROCESS | 1642 | Values: 782.4 820.6 844.0 983.2 986.2 38970.6 40074.0 41329.0 41541.4 41671.5 39682.4 39926.4 40034.8 40108.4 40135.6 8236.7 8392.6 11151.5 11445.9 11679.6 11258.2 11329.0 11330.3 11332.3 11333.9 11267.8 11316.7 11327.2 11334.7 11339.5 11272.9 11279.9 11298.5 11301.3 11311.7 11074.4 11081.9 11168.8 11175.9 11176.8 34217.5 34463.0 34492.0 34532.8 34533.1 3906.0 3944.7 3950.7 3959.8 3965.0 24729.0 24843.8 25731.2 26181.2 27113.5 3148.6 3174.6 3188.1 3225.1 3249.0 308985.0 309854.0 356404.0 363030.0 369992.0 42605.0 42903.4 42911.0 42957.8 43018.9 44751.8 45217.9 45423.3 45462.0 45556.8 11857.8 12514.9 14229.9 14399.1 14416.9 12479.9 12635.9 12652.4 12680.5 12709.4 11817.9 11834.8 11839.0 11878.2 11910.2 4907.3 5747.9 5754.4 5777.8 5787.7 956.6 980.0 980.8 989.0 995.3 28437.3 28730.4 28992.6 29160.3 29163.6 929.9 1058.7 1071.7 1098.6 1153.7 13456.5 14146.6 14457.8 14696.7 14768.3 484515.0 484544.0 484556.0 486698.0 487390.0 46513.0 47264.3 47268.0 47377.0 47537.5 1483.5 1491.3 1508.1 1515.6 1524.1 25353.4 26458.1 26773.1 27014.9 27104.7 28997.0 45071.6 45908.5 47677.0 48476.5 484419.0 486566.0 486661.0 488257.0 489160.0 27139.0 27588.2 32420.0 32938.3 33062.7 39618.8 39718.4 40337.0 40385.2 41163.4 27913.9 28139.0 28595.7 28988.0 29401.4 24796.9 28256.9 29004.7 29083.4 29799.4 33603.0 34435.0 34903.5 34983.3 35251.8 28894.9 29168.1 29208.4 29671.0 30064.1 23644.9 23746.9 23818.2 24191.8 24224.4 07:22:26 INFO - PROCESS | 1642 | 07:22:26 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:45535.64 Median:21273.07 stddev:132225.88 (621.6%) stddev-sans-first:132553.41 07:22:26 INFO - PROCESS | 1642 | Values: 420.8 438.1 440.5 446.2 446.5 21356.1 21670.0 22068.0 22211.8 22812.5 18815.5 22297.6 22796.9 22955.8 23052.8 5844.9 6857.0 7120.8 7136.7 7149.2 6875.2 7024.7 7037.5 7045.8 7056.5 6746.8 6985.3 7036.5 7037.0 7043.9 5702.7 6827.9 6924.8 6957.2 7013.0 6769.8 6797.2 6807.9 6829.1 6830.2 24802.9 25765.1 26447.5 26669.5 26918.7 2478.8 2998.2 3040.2 3055.7 3062.8 16197.4 16286.3 16404.7 16581.9 16598.8 2169.5 2174.0 2208.2 2249.7 2304.4 170033.0 170773.0 178065.0 178266.2 181232.0 14498.0 19682.3 20086.7 20567.0 20840.1 29285.0 29847.6 30207.2 30208.7 30246.2 10350.9 10520.7 10734.9 10751.0 10819.2 9072.5 9107.0 9131.5 9252.5 9262.4 9158.6 9335.9 9367.7 9379.5 9418.9 4378.0 4441.3 4446.5 4464.9 4473.0 991.8 1008.3 1036.8 1039.3 1070.3 18346.3 20844.7 21127.6 21168.7 21190.0 1032.5 1037.1 1052.6 1058.9 1082.8 10207.8 10414.9 10832.4 10917.1 10969.2 764273.0 780918.0 790193.0 801001.0 805001.0 166619.3 169573.3 169992.0 170612.1 172104.0 1242.8 1279.6 1293.0 1303.6 1304.4 18149.3 18683.0 18781.7 18951.4 18993.1 66566.0 66623.7 66760.0 67499.7 68821.7 80333.6 97289.0 99547.0 99662.0 100339.5 11294.4 11454.3 11602.4 11837.7 11896.0 12569.2 12593.6 12736.2 12891.9 12949.9 10768.9 10808.1 10984.1 11075.5 11483.7 10188.6 10372.0 10425.5 10437.0 10688.0 11092.6 11754.2 11959.0 11978.8 12075.0 11410.2 11579.5 11676.6 11913.9 11981.6 10860.9 11146.7 11154.9 11362.9 11465.7 07:22:26 INFO - PROCESS | 1642 | 07:22:26 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:7027.51 Median:2476.40 stddev:4338.54 (175.2%) stddev-sans-first:4331.11 07:22:26 INFO - PROCESS | 1642 | Values: 1538.7 1652.9 1672.7 1689.5 1690.8 10985.9 11646.2 11663.9 11689.6 12068.0 11647.9 11759.8 11826.6 11827.4 11849.5 2484.8 2504.5 2507.4 2518.8 2519.9 2451.6 2467.2 2477.6 2480.4 2490.5 2417.3 2441.5 2443.7 2445.2 2463.1 2360.7 2362.6 2370.7 2401.7 2435.1 2469.2 2475.2 2485.8 2487.5 2498.4 11485.5 11662.2 11713.8 11720.9 11756.8 5299.5 5958.9 6123.2 6134.6 6144.6 3682.9 3756.2 3792.5 3829.9 3869.2 1288.4 1922.8 1928.6 1959.1 1987.6 14474.3 14832.9 15429.2 16136.8 16255.1 4819.1 4834.1 4983.6 4991.4 5007.2 12129.9 12245.1 12334.6 12345.9 12348.0 6099.2 6100.1 6102.8 6135.1 6138.1 5222.7 5347.6 5401.1 5589.1 5656.7 4220.6 4249.7 4297.0 4348.7 4352.1 1037.7 1170.1 1185.4 1193.1 1215.4 980.6 999.7 1013.0 1024.7 1025.3 6874.3 6923.7 7014.3 7081.6 7084.1 1027.4 1051.0 1052.3 1063.9 1094.6 4617.9 4701.5 4714.2 4744.0 4826.8 13360.7 13567.3 14108.2 14511.6 14571.9 14034.0 14046.6 14287.2 14287.6 14325.7 8803.3 10391.4 10628.6 10661.6 10777.5 5968.6 6939.4 6968.5 7302.2 7326.1 11717.8 11825.5 12207.3 12324.2 12723.2 12882.7 12980.6 13063.0 13113.9 13270.3 9430.4 9694.9 9727.5 9809.0 9839.9 10567.8 10589.8 10763.1 10824.1 10860.8 9266.9 9273.2 9304.7 9319.2 9437.7 8991.3 9018.0 9037.2 9085.0 9130.6 8152.0 8331.7 8355.5 8363.3 8403.0 5366.9 5377.3 6018.5 6048.2 6082.9 4559.7 5630.1 5776.1 5782.9 5925.1 07:22:26 INFO - PROCESS | 1642 | 07:22:26 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4799.87 Median:5150.27 stddev:1359.22 (26.4%) stddev-sans-first:1320.90 07:22:26 INFO - PROCESS | 1642 | Values: 325.5 387.9 392.2 394.7 398.8 4775.4 5183.7 5351.5 5515.3 5521.8 4990.0 5095.2 5337.7 5350.4 5395.5 3726.8 4096.9 4316.8 4380.7 4413.4 4388.9 4434.8 4437.4 4444.8 4446.7 4219.6 4369.2 4420.5 4429.6 4434.6 4152.7 4334.9 4374.8 4389.8 4420.0 4059.7 4060.6 4091.2 4093.6 4123.6 4316.5 4565.5 4566.0 4822.7 5222.7 4217.8 4452.4 4642.0 5073.8 5169.7 5150.0 5377.6 5503.8 5602.6 5812.2 4230.1 4534.8 4558.0 4613.6 4631.3 5200.2 5380.9 6089.3 6113.8 6160.6 3983.3 4191.2 4205.4 4553.7 4751.7 4058.5 4531.0 4783.8 4842.2 4844.4 4363.8 4494.1 4818.8 5150.6 5169.7 5312.7 5576.4 5696.8 5774.2 5815.4 1618.9 1621.9 1623.7 1629.3 1641.5 1165.3 1200.0 1216.7 1234.3 1238.6 4659.7 4875.3 4887.8 4983.0 5395.8 4958.8 5442.5 5622.4 5991.5 5998.8 4528.0 4877.6 5260.7 5394.9 5410.1 2757.4 2760.7 3113.9 3123.4 3125.3 5771.4 5929.3 5983.5 6193.6 6225.7 5973.0 6082.8 6188.6 6194.5 6208.3 5534.5 6066.4 6194.8 6242.3 6245.6 5957.3 5986.6 6014.6 6049.7 6152.1 5560.5 5679.3 5944.7 5972.5 5973.7 5454.6 6264.4 6344.7 6376.8 6493.6 4549.4 4604.0 4708.7 4748.4 4749.8 4983.6 5223.6 5863.0 6000.9 6036.3 5119.5 5150.9 5843.6 5851.6 5885.8 4955.8 5316.7 5499.2 5728.4 5791.7 5533.5 5675.6 5725.5 5736.7 5790.5 5619.8 5640.3 5645.4 5892.9 5938.0 5015.0 5364.3 5378.5 5678.7 5711.0 07:22:26 INFO - PROCESS | 1642 | 07:22:26 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:20984.72 Median:24110.84 stddev:30422.38 (126.2%) stddev-sans-first:30469.76 07:22:26 INFO - PROCESS | 1642 | Values: 745.4 761.9 808.5 863.6 889.2 16375.6 17611.5 17617.8 17639.0 17688.1 16149.8 16261.9 16346.6 16363.9 16400.2 974.7 987.5 988.8 991.5 992.5 1014.4 1015.6 1024.5 1026.1 1027.1 1050.0 1051.5 1055.6 1068.1 1068.2 1063.4 1063.5 1064.0 1064.0 1064.1 787.0 787.1 787.5 788.2 788.9 17551.5 17842.5 17850.4 18265.5 18527.1 14723.6 14935.4 14963.7 15001.2 15190.4 11076.2 11101.9 11146.3 11336.9 11566.7 3428.9 3762.5 3908.7 4011.4 4064.2 90688.0 94191.0 111447.0 112127.9 113004.4 10603.5 11549.8 11553.4 11657.2 11704.6 24094.5 24127.2 24231.4 24290.1 24300.3 7673.7 7722.3 7888.0 7895.6 8048.8 3884.7 4644.5 4651.4 4660.8 4689.2 3498.2 3630.9 3688.7 3698.5 3702.2 595.2 677.0 678.1 680.2 694.0 691.9 703.3 703.4 726.5 746.4 10508.3 10656.6 10670.5 11163.6 11166.7 649.9 714.6 736.1 750.0 758.5 4983.9 5214.8 5239.1 5244.2 5290.7 107017.0 108122.0 110404.3 110493.0 110573.9 84696.2 96733.0 97460.0 99803.0 100133.5 64870.7 68819.6 83162.0 83444.6 83623.2 8226.0 9065.6 9662.1 9867.4 10141.6 45426.0 47426.1 51054.7 54789.2 55818.7 69533.7 69991.6 71149.0 71471.2 72610.3 11155.4 11283.7 11577.0 11621.0 11666.8 11434.4 11862.5 11968.3 11988.9 11990.2 12249.5 12610.2 12640.1 12857.4 12939.2 12344.8 12681.7 12786.6 12937.5 13307.3 11461.2 11940.2 12094.8 12355.9 12484.6 9191.5 9548.6 9645.1 9818.3 10245.6 9214.7 10216.8 10460.2 10754.6 10763.0 07:22:26 INFO - PROCESS | 1642 | -------- Summary: end -------- 07:22:26 INFO - PROCESS | 1642 | 07:22:27 INFO - TEST-INFO | 1642: exit 0 07:22:27 INFO - TEST-OK | dromaeo_css | took 1116495ms 07:22:27 INFO - TEST-START | kraken 07:22:27 INFO - operating with platform_type : mac_ 07:22:27 INFO - Initialising browser for kraken test... 07:22:27 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/tmp2DpV_5/profile http://localhost:49224/getInfo.html) 07:22:29 INFO - PROCESS | 1654 | 2017-05-26 07:22:29.676 plugin-container[1656:10188] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 07:22:29 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:22:29 INFO - PROCESS | 1654 | 2017-05-26 07:22:29.676 plugin-container[1656:10188] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0f, name = 'com.apple.CFPasteboardClient' 07:22:29 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:22:29 INFO - PROCESS | 1654 | 2017-05-26 07:22:29.676 plugin-container[1656:10188] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:22:29 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 07:22:29 INFO - PROCESS | 1654 | colorDepth:24 07:22:29 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 07:22:29 INFO - PROCESS | 1654 | __metrics 07:22:29 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 07:22:30 INFO - TEST-INFO | 1654: exit 0 07:22:30 INFO - Browser initialized. 07:22:30 INFO - Running cycle 1/1 for kraken test... 07:22:30 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/tmp2DpV_5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:22:32 INFO - PROCESS | 1657 | 2017-05-26 07:22:32.726 plugin-container[1658:10319] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 07:22:32 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:22:32 INFO - PROCESS | 1657 | 2017-05-26 07:22:32.727 plugin-container[1658:10319] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 07:22:32 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:22:32 INFO - PROCESS | 1657 | 2017-05-26 07:22:32.727 plugin-container[1658:10319] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:22:42 INFO - PROCESS | 1657 | RSS: Main: 174350336 07:22:42 INFO - PROCESS | 1657 | 07:22:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 07:22:46 INFO - PROCESS | 1657 | RSS: Main: 187916288 07:22:46 INFO - PROCESS | 1657 | 07:22:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 07:22:49 INFO - PROCESS | 1657 | RSS: Main: 195907584 07:22:49 INFO - PROCESS | 1657 | 07:22:53 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 07:22:53 INFO - PROCESS | 1657 | RSS: Main: 199692288 07:22:53 INFO - PROCESS | 1657 | 07:22:55 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 07:22:56 INFO - PROCESS | 1657 | RSS: Main: 201035776 07:22:56 INFO - PROCESS | 1657 | 07:22:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:22:58 INFO - PROCESS | 1657 | RSS: Main: 201064448 07:22:58 INFO - PROCESS | 1657 | 07:23:01 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 07:23:02 INFO - PROCESS | 1657 | RSS: Main: 205152256 07:23:02 INFO - PROCESS | 1657 | 07:23:05 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 07:23:06 INFO - PROCESS | 1657 | RSS: Main: 204918784 07:23:06 INFO - PROCESS | 1657 | 07:23:09 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 07:23:10 INFO - PROCESS | 1657 | RSS: Main: 204812288 07:23:10 INFO - PROCESS | 1657 | 07:23:11 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:23:12 INFO - PROCESS | 1657 | RSS: Main: 204374016 07:23:12 INFO - PROCESS | 1657 | 07:23:14 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:23:14 INFO - PROCESS | 1657 | RSS: Main: 204353536 07:23:14 INFO - PROCESS | 1657 | 07:23:16 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:23:17 INFO - PROCESS | 1657 | RSS: Main: 204660736 07:23:17 INFO - PROCESS | 1657 | 07:23:20 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:23:21 INFO - PROCESS | 1657 | RSS: Main: 205045760 07:23:21 INFO - PROCESS | 1657 | 07:23:24 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:23:24 INFO - PROCESS | 1657 | RSS: Main: 204808192 07:23:24 INFO - PROCESS | 1657 | 07:23:26 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 07:23:26 INFO - PROCESS | 1657 | RSS: Main: 206540800 07:23:26 INFO - PROCESS | 1657 | 07:23:26 INFO - PROCESS | 1657 | __start_tp_report 07:23:26 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 07:23:26 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 07:23:26 INFO - PROCESS | 1657 | |i|pagename|runs| 07:23:26 INFO - PROCESS | 1657 | |0;ai-astar;108;119;128;168;125;122;120;119;120;120 07:23:26 INFO - PROCESS | 1657 | |1;audio-beat-detection;141;108;120;140;111;108;120;135;137;128 07:23:26 INFO - PROCESS | 1657 | |2;audio-dft;253;198;256;196;237;200;193;198;194;195 07:23:26 INFO - PROCESS | 1657 | |3;audio-fft;83;59;62;95;61;55;71;84;67;70 07:23:26 INFO - PROCESS | 1657 | |4;audio-oscillator;75;71;84;70;73;70;80;71;68;92 07:23:26 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;97;132;90;90;90;102;91;91;90;96 07:23:26 INFO - PROCESS | 1657 | |6;imaging-darkroom;97;97;97;97;97;98;116;117;97;97 07:23:26 INFO - PROCESS | 1657 | |7;imaging-desaturate;109;85;143;149;88;138;141;89;139;140 07:23:26 INFO - PROCESS | 1657 | |8;json-parse-financial;66;67;77;67;64;65;64;64;64;67 07:23:26 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;57;64;61;68;64;58;58;59;60;61 07:23:26 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;89;75;97;101;100;83;85;84;88;95 07:23:26 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;138;138;133;133;168;134;150;166;196;140 07:23:26 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;164;162;190;213;221;186;162;156;165;197 07:23:26 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;65;62;75;82;87;65;63;64;84;82 07:23:26 INFO - PROCESS | 1657 | __end_tp_report 07:23:26 INFO - PROCESS | 1657 | __start_cc_report 07:23:26 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,988 07:23:26 INFO - PROCESS | 1657 | __end_cc_report 07:23:26 INFO - PROCESS | 1657 | __startTimestamp1495808606989__endTimestamp 07:23:26 INFO - PROCESS | 1657 | 07:23:26 INFO - PROCESS | 1657 | ------- Summary: start ------- 07:23:26 INFO - PROCESS | 1657 | Number of tests: 14 07:23:26 INFO - PROCESS | 1657 | 07:23:26 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:124.90 Median:121.00 stddev:16.00 (13.2%) stddev-sans-first:15.75 07:23:26 INFO - PROCESS | 1657 | Values: 108.0 119.0 128.0 168.0 125.0 122.0 120.0 119.0 120.0 120.0 07:23:26 INFO - PROCESS | 1657 | 07:23:26 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:131.50 stddev:13.16 (10.0%) stddev-sans-first:12.58 07:23:26 INFO - PROCESS | 1657 | Values: 141.0 108.0 120.0 140.0 111.0 108.0 120.0 135.0 137.0 128.0 07:23:26 INFO - PROCESS | 1657 | 07:23:26 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:212.00 Median:199.00 stddev:25.84 (13.0%) stddev-sans-first:22.75 07:23:26 INFO - PROCESS | 1657 | Values: 253.0 198.0 256.0 196.0 237.0 200.0 193.0 198.0 194.0 195.0 07:23:26 INFO - PROCESS | 1657 | 07:23:26 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:70.70 Median:70.50 stddev:12.85 (18.2%) stddev-sans-first:12.84 07:23:26 INFO - PROCESS | 1657 | Values: 83.0 59.0 62.0 95.0 61.0 55.0 71.0 84.0 67.0 70.0 07:23:27 INFO - PROCESS | 1657 | 07:23:27 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:75.40 Median:74.00 stddev:7.66 (10.4%) stddev-sans-first:8.13 07:23:27 INFO - PROCESS | 1657 | Values: 75.0 71.0 84.0 70.0 73.0 70.0 80.0 71.0 68.0 92.0 07:23:27 INFO - PROCESS | 1657 | 07:23:27 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:96.90 Median:90.50 stddev:12.99 (14.4%) stddev-sans-first:13.78 07:23:27 INFO - PROCESS | 1657 | Values: 97.0 132.0 90.0 90.0 90.0 102.0 91.0 91.0 90.0 96.0 07:23:27 INFO - PROCESS | 1657 | 07:23:27 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:101.00 Median:97.00 stddev:8.18 (8.4%) stddev-sans-first:8.55 07:23:27 INFO - PROCESS | 1657 | Values: 97.0 97.0 97.0 97.0 97.0 98.0 116.0 117.0 97.0 97.0 07:23:27 INFO - PROCESS | 1657 | 07:23:27 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:122.10 Median:146.00 stddev:26.21 (18.0%) stddev-sans-first:27.37 07:23:27 INFO - PROCESS | 1657 | Values: 109.0 85.0 143.0 149.0 88.0 138.0 141.0 89.0 139.0 140.0 07:23:27 INFO - PROCESS | 1657 | 07:23:27 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:66.50 Median:66.50 stddev:3.92 (5.9%) stddev-sans-first:4.16 07:23:27 INFO - PROCESS | 1657 | Values: 66.0 67.0 77.0 67.0 64.0 65.0 64.0 64.0 64.0 67.0 07:23:27 INFO - PROCESS | 1657 | 07:23:27 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:61.00 stddev:3.43 (5.6%) stddev-sans-first:3.32 07:23:27 INFO - PROCESS | 1657 | Values: 57.0 64.0 61.0 68.0 64.0 58.0 58.0 59.0 60.0 61.0 07:23:27 INFO - PROCESS | 1657 | 07:23:27 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:89.70 Median:86.50 stddev:8.39 (9.7%) stddev-sans-first:8.90 07:23:27 INFO - PROCESS | 1657 | Values: 89.0 75.0 97.0 101.0 100.0 83.0 85.0 84.0 88.0 95.0 07:23:27 INFO - PROCESS | 1657 | 07:23:27 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:145.00 stddev:20.86 (14.4%) stddev-sans-first:21.70 07:23:27 INFO - PROCESS | 1657 | Values: 138.0 138.0 133.0 133.0 168.0 134.0 150.0 166.0 196.0 140.0 07:23:27 INFO - PROCESS | 1657 | 07:23:27 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.60 Median:188.00 stddev:23.27 (12.4%) stddev-sans-first:23.80 07:23:27 INFO - PROCESS | 1657 | Values: 164.0 162.0 190.0 213.0 221.0 186.0 162.0 156.0 165.0 197.0 07:23:27 INFO - PROCESS | 1657 | 07:23:27 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:78.50 stddev:10.07 (12.8%) stddev-sans-first:10.27 07:23:27 INFO - PROCESS | 1657 | Values: 65.0 62.0 75.0 82.0 87.0 65.0 63.0 64.0 84.0 82.0 07:23:27 INFO - PROCESS | 1657 | -------- Summary: end -------- 07:23:27 INFO - PROCESS | 1657 | 07:23:27 INFO - TEST-INFO | 1657: exit 0 07:23:27 INFO - TEST-OK | kraken | took 60656ms 07:23:27 INFO - SUITE-END | took 1177s 07:23:28 INFO - Completed test suite (00:19:37) 07:23:28 INFO - javascript benchmark 07:23:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7925.251025580444, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3624.76439031463, 3672.008557689424, 3695.6304369563045, 3707.277080969212, 3708.388115960867, 6195.0, 6223.844403889905, 6238.251409830816, 6239.095331176976, 6275.152854364659, 6180.153830028294, 6243.937560624392, 6264.8120556383265, 6269.780557680482, 6298.527610429224, 4277.3156294992805, 5567.582431317667, 5604.131359639263, 5621.241132447119, 5635.464630860061, 4661.8834529136875, 5380.166074258496, 5690.630109042899, 5717.8570535736735, 5768.250127483421, 5458.781648734046, 5495.53287970521, 5648.096304591266, 5706.058500347458, 5716.371199168088, 4301.204277208726, 5576.637518561281, 5599.8880022399735, 5618.410066942994, 5645.887082258373, 5519.337679478448, 5637.746301416446, 5639.323281206241, 5665.745041473144, 5671.177679236487, 4653.6044436222965, 6249.531285153586, 6268.15379923709, 6277.591956522857, 6291.905621415683, 6343.175387199681, 6393.904091438632, 6401.135846660734, 6553.2136143663065, 6639.302873198341, 6702.262751097375, 6739.258681545026, 6760.459163266927, 6764.154480689914, 6793.626350550718, 6259.593126446766, 6318.589291696071, 6325.746970121144, 6332.841678958063, 6337.397947195009, 6578.638174900427, 6664.333566643297, 6801.557898736566, 6923.861522769516, 6950.756723514653, 6383.265924418752, 6440.259370172396, 6479.5788273762055, 6580.0, 6581.86836263272, 6771.424428923597, 6772.0, 6772.661366931634, 6777.966110169417, 6807.4894382921475, 6588.068309602277, 6610.206775186912, 6676.232233293136, 6693.096431981718, 6693.330666933268, 5090.592752579785, 6244.03217501279, 6528.314526974695, 6567.376099270562, 6601.3728695773825, 5218.530332270038, 5279.340082489689, 5323.707196104174, 5368.355797304349, 5425.403205647375, 4690.108879312919, 4756.025014871943, 4774.1883879739835, 4779.0680817241, 4784.40194975628, 5239.240310155006, 6237.563370564108, 6321.956877115316, 6323.430891219812, 6328.651924144124, 6407.967960160169, 6450.4839612830865, 6642.43539299165, 6737.966310168418, 6764.966175169191, 6231.4391704745885, 6288.937110628926, 6307.337729538424, 6333.429991300805, 6336.429721325103, 5955.37468565803, 5971.4327138921735, 5971.9701401493585, 5973.940260597338, 6002.339742628307, 6569.967150164219, 6699.995000749732, 6918.515703900679, 6978.337057979484, 6991.196012458632, 6625.17185351831, 6655.134832471747, 6659.900101498578, 6679.365460281266, 6728.226253980659, 5098.413682426569, 5836.678982655994, 6999.12510936133, 7004.929950700428, 7021.964890175721, 5327.0, 6634.303398143318, 6661.733530658719, 6667.299933506912, 6681.2650608434005, 6263.3736626337, 6380.617162970237, 6544.181977252843, 6576.309487503743, 6658.134442522635, 5411.945880541144, 6542.83642908912, 6562.376574225441, 6575.934240657723, 6576.506761992774, 6518.217813862367, 6579.901301480386, 6581.3747693969, 6582.637954912334, 6600.966995164993, 6087.208662873798, 6262.937370626418, 6299.622022678654, 6310.5267104968225, 6330.113784070326, 6544.8363790905605, 6559.868802624016, 6562.737490500326, 6582.210134783857, 6605.5376123670885, 6212.378762123752, 6215.81352559424, 6356.841078973062, 6388.0, 6412.909805333198], "value": 6073.077303080206, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.3584660578324, 820.5897051474263, 844.0462277626282, 983.1692220074035, 986.1863962231157, 38970.61029389705, 40073.99815004626, 41329.0, 41541.37687934683, 41671.54149604764, 39682.40476392856, 39926.401103983466, 40034.799826000904, 40108.398374024415, 40135.59864401355, 8236.711715089974, 8392.622331995044, 11151.498182581763, 11445.942770286138, 11679.5912143075, 11258.211925165242, 11329.0, 11330.320180789138, 11332.263402878827, 11333.886661133407, 11267.774644507146, 11316.660500185008, 11327.150463715214, 11334.659960201207, 11339.489722962486, 11272.943635281812, 11279.887201128007, 11298.548058077626, 11301.321920684784, 11311.660650180507, 11074.446277686084, 11081.889181108247, 11168.832467512993, 11175.888241117647, 11176.776464470666, 34217.48673769896, 34463.0, 34492.0, 34532.82733586316, 34533.13667158316, 3906.0, 3944.7435916665327, 3950.7432016919097, 3959.762414255154, 3964.960350396517, 24729.0, 24843.751562484325, 25731.228063158134, 26181.214563563124, 27113.457730845468, 3148.5592017117624, 3174.619045714506, 3188.0754581171664, 3225.0808519572042, 3248.96751032489, 308985.0, 309854.0, 356404.0, 363030.0, 369991.9999999973, 42605.0, 42903.35644965328, 42911.0, 42957.785211073744, 43018.924526886454, 44751.77624111858, 45217.869553260774, 45423.31865022027, 45462.0, 45556.77221613937, 11857.762844743229, 12514.937425312997, 14229.92885035589, 14399.136051836924, 14416.85583144176, 12479.875201248053, 12635.93682031584, 12652.367381630882, 12680.492780288869, 12709.428075736614, 11817.881821181678, 11834.822477662841, 11839.0, 11878.168528202943, 11910.225835320676, 4907.312976183337, 5747.942520574741, 5754.395788442153, 5777.797777077866, 5787.681677507778, 956.5982287439292, 979.9804003920025, 980.8185485685028, 988.9851652225223, 995.2833959549113, 28437.289067773472, 28730.425391492052, 28992.565111523345, 29160.27099322534, 29163.562546561818, 929.9488528131018, 1058.714147180257, 1071.6892101290694, 1098.5715570927346, 1153.6942710181809, 13456.46174153023, 14146.575602731935, 14457.78313325301, 14696.706065878836, 14768.335424905905, 484515.0, 484544.0, 484556.0, 486698.0, 487390.0, 46513.0, 47264.291035634495, 47268.0, 47377.0, 47537.52462475331, 1483.458537633756, 1491.2841835919037, 1508.102678906049, 1515.5983664328962, 1524.1312451902531, 25353.366165846, 26458.07396741105, 26773.062942796907, 27014.864925675247, 27104.728952710222, 28997.0, 45071.647850564535, 45908.54091459043, 47677.0, 48476.5152348472, 484419.0, 486566.0, 486661.0, 488257.0, 489160.0, 27139.0, 27588.17235482939, 32420.027399178063, 32938.341233174724, 33062.66937330596, 39618.80190599144, 39718.404223936086, 40337.0, 40385.1922961545, 41163.38254926239, 27913.860430697718, 28139.0, 28595.714042859305, 28988.0, 29401.41197176002, 24796.876015619808, 28256.86972521076, 29004.7099529002, 29083.418331632827, 29799.404011920073, 33603.0, 34435.0, 34903.476447852794, 34983.300333992665, 35251.82374088113, 28894.855525722236, 29168.124956251348, 29208.41583168282, 29670.961516346822, 30064.09807705685, 23644.8817755917, 23746.931388087232, 23818.16636417717, 24191.758082419656, 24224.394390140387], "value": 18478.543815241712, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.77698819625607, 438.0887753472777, 440.5044325134224, 446.18571107728394, 446.535602972908, 21356.145754169833, 21670.0, 22068.0, 22211.777882221173, 22812.543749125027, 18815.529611759714, 22297.554048919053, 22796.88601556994, 22955.770442295572, 23052.769472305274, 5844.94790937631, 6856.965715171417, 7120.750773722921, 7136.678849451787, 7149.249328820477, 6875.174979002527, 7024.683889224997, 7037.542559733626, 7045.788626341218, 7056.470764692643, 6746.797596072101, 6985.301469853025, 7036.542624729376, 7037.0, 7043.894341584854, 5702.7433765480655, 6827.931720682779, 6924.792256232296, 6957.199922008957, 7012.964935175342, 6769.796906092776, 6797.218319893226, 6807.863842723167, 6829.112215411965, 6830.248672646005, 24802.883870225884, 25765.098221562155, 26447.47105057888, 26669.46661066787, 26918.730812691818, 2478.8264821462503, 2998.175501737018, 3040.1791516290477, 3055.6791536888527, 3062.7549796016488, 16197.433089841918, 16286.26711797972, 16404.6719065618, 16581.91709041447, 16598.834011659972, 2169.4684802223555, 2173.9565208695735, 2208.2271205078255, 2249.6737972993824, 2304.4238940264936, 170033.0, 170773.0, 178065.0, 178266.21733782627, 181232.0, 14498.0, 19682.311119111047, 20086.69869951952, 20567.0, 20840.06219720086, 29285.0, 29847.552286715283, 30207.244818879266, 30208.697913020587, 30246.243843903638, 10350.896491034993, 10520.736981575368, 10734.946325268324, 10750.97865702757, 10819.242653014371, 9072.455652660861, 9107.043760405195, 9131.452112873249, 9252.49111298885, 9262.351635385594, 9158.633654653873, 9335.859962100574, 9367.672131475365, 9379.531023448937, 9418.858717119248, 4377.9781101094295, 4441.268619798865, 4446.4886538047895, 4464.910701785946, 4473.0, 991.7949691125174, 1008.2992320337295, 1036.7926414717087, 1039.298473530364, 1070.250824422907, 18346.266149353876, 20844.687329690067, 21127.620598412206, 21168.682469762967, 21190.0, 1032.4528000159928, 1037.1391744851755, 1052.610534102387, 1058.878229003659, 1082.7617924056694, 10207.846882296772, 10414.8619684287, 10832.350058996486, 10917.126629869592, 10969.23215374932, 764273.0, 780918.0, 790193.0, 801001.0, 805001.0, 166619.33380666038, 169573.3042669582, 169992.0, 170612.146939267, 172104.0, 1242.7576622558515, 1279.5777393460137, 1293.0366876676935, 1303.6089173247988, 1304.3543445994103, 18149.27402903869, 18683.0, 18781.718274226172, 18951.43145705631, 18993.14530846143, 66566.0, 66623.66752664822, 66760.0, 67499.66250168718, 68821.65589172022, 80333.5983320103, 97289.0, 99547.0, 99662.0, 100339.49830250801, 11294.378809165575, 11454.312741235553, 11602.419879006186, 11837.704057398634, 11896.048316134862, 12569.183003104768, 12593.622191334274, 12736.23582585048, 12891.904188144114, 12949.935250323688, 10768.892311076788, 10808.135349171891, 10984.06635435997, 11075.501602427748, 11483.655490335303, 10188.643397481053, 10371.962803719567, 10425.53085111187, 10437.02692641767, 10688.038076573146, 11092.556297748, 11754.23597466162, 11958.983486403753, 11978.820317695416, 12075.0, 11410.201285909907, 11579.478923448634, 11676.591319303783, 11913.880861191625, 11981.64055078349, 10860.945695271788, 11146.721331966766, 11154.944225278821, 11362.886371136514, 11465.656030319104], "value": 11865.512502218618, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1538.6922615476904, 1652.876034297428, 1672.665466906619, 1689.45937300064, 1690.8140104588485, 10985.945070274647, 11646.24299420538, 11663.883361166396, 11689.59086431975, 12068.0, 11647.941760291187, 11759.823602645945, 11826.58606948757, 11827.408629568508, 11849.46677399517, 2484.8136389770793, 2504.524140413325, 2507.3982244261338, 2518.8110891683104, 2519.886605102775, 2451.6445115458337, 2467.198160597804, 2477.6283557466345, 2480.4295012147218, 2490.5392502387117, 2417.3352328109822, 2441.4628781668002, 2443.7067551893797, 2445.2297526279253, 2463.1132792194794, 2360.6931098957198, 2362.5865473542062, 2370.7036620422446, 2401.6637670726113, 2435.1355268879647, 2469.2098528470897, 2475.170817776047, 2485.8135639827087, 2487.502499500089, 2498.375406148463, 11485.48315325804, 11662.241954272946, 11713.824292635618, 11720.941395293054, 11756.823647645208, 5299.496547827989, 5958.880822383528, 6123.173371594867, 6134.570580059398, 6144.631322120687, 3682.9447558286647, 3756.1924186299802, 3792.50697409335, 3829.8659546916, 3869.187470631171, 1288.3815768431205, 1922.8173323534243, 1928.623918335925, 1959.1340766821224, 1987.552800619849, 14474.348654310581, 14832.851671483362, 15429.22853857314, 16136.838631613651, 16255.105969171691, 4819.108464933928, 4834.057358814997, 4983.626228032912, 4991.401031876198, 5007.24891266313, 12129.87870121305, 12245.08161887862, 12334.568290109983, 12345.888870001743, 12347.950424214043, 6099.176611157525, 6100.054491553817, 6102.7558897644485, 6135.141080248769, 6138.14066030756, 5222.7127507987425, 5347.625666203407, 5401.108817045144, 5589.105743081169, 5656.745446454919, 4220.6412454941055, 4249.723767955073, 4296.957030429656, 4348.7390756554705, 4352.107817897323, 1037.725002874239, 1170.0639488409238, 1185.4013723069795, 1193.0574845871838, 1215.4226742297515, 980.568549838069, 999.6554634017235, 1012.9898701012895, 1024.7130803375067, 1025.2874252394663, 6874.312568743186, 6923.6884340204815, 7014.298570143047, 7081.575105493687, 7084.114485689289, 1027.445179603021, 1050.9857987042387, 1052.3422860712055, 1063.8776540697918, 1094.584058057933, 4617.907641847144, 4701.48283688794, 4714.22215334466, 4744.0037592105355, 4826.758662066883, 13360.732785344433, 13567.253801041037, 14108.224047677271, 14511.637209069857, 14571.85428145705, 14034.017618766793, 14046.648833779238, 14287.214203218922, 14287.64280892986, 14325.713485730226, 8803.339749518811, 10391.428471434143, 10628.574857005788, 10661.573537058586, 10777.515011824487, 5968.612040217372, 6939.410150137093, 6968.477364197604, 7302.233265507258, 7326.084239470067, 11717.824232636347, 11825.46785394642, 12207.328596927255, 12324.198927069712, 12723.23660580368, 12882.67793305175, 12980.610581682564, 13063.0, 13113.868861311264, 13270.270135142273, 9430.434173949585, 9694.854577181206, 9727.513624318613, 9809.0, 9839.852402214116, 10567.78864422692, 10589.841152382874, 10763.138948883914, 10824.079953204066, 10860.782784344427, 9266.907330926873, 9273.165415112673, 9304.72085837426, 9319.20786733108, 9437.669681561112, 8991.28069754405, 9018.008019118022, 9037.231835293811, 9084.954575227082, 9130.589123489326, 8152.0, 8331.708390206313, 8355.456895301786, 8363.330933525182, 8403.033651129954, 5366.892662146814, 5377.300950876361, 6018.470168737978, 6048.183495228088, 6082.90875636857, 4559.658025648156, 5630.071038278638, 5776.133579963039, 5782.942170578409, 5925.111233315038], "value": 5425.5848217588145, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.51660783736133, 387.893329334433, 392.24688597892043, 394.67439362525914, 398.82441282802307, 4775.379200703904, 5183.740812959356, 5351.545118664913, 5515.255440515529, 5521.751521181546, 4989.975050124754, 5095.235714642806, 5337.733113344327, 5350.384705758829, 5395.541378982782, 3726.7577607455564, 4096.877093687194, 4316.848910288133, 4380.671449641273, 4413.374042444741, 4388.89027774305, 4434.778261086949, 4437.378766972626, 4444.844430444935, 4446.733196008234, 4219.620234178923, 4369.235383807837, 4420.469543654766, 4429.645628349725, 4434.645228381738, 4152.688548358885, 4334.869953901404, 4374.803133858984, 4389.824407023715, 4420.0, 4059.6549293310104, 4060.573639767841, 4091.181763647253, 4093.63157315843, 4123.628873401408, 4316.546762589915, 4565.539027511198, 4566.036604260167, 4822.683698090224, 5222.738863056832, 4217.768022758747, 4452.443444569429, 4642.0, 5073.771680274396, 5169.663971841818, 5149.970005998778, 5377.623566350357, 5503.834884953458, 5602.579806514527, 5812.157237200625, 4230.132822771325, 4534.752942940685, 4558.014500502177, 4613.561711637389, 4631.305304204363, 5200.167973124282, 5380.892382152374, 6089.269287685462, 6113.786017489411, 6160.599561028519, 3983.2830090583393, 4191.245575796386, 4205.3902184183735, 4553.681242313072, 4751.740788691, 4058.5332686741403, 4531.0, 4783.784729687103, 4842.201036828884, 4844.4186697596515, 4363.80362883671, 4494.07871386365, 4818.831340903052, 5150.562202212779, 5169.741512924339, 5312.698388894668, 5576.442355764391, 5696.772129114872, 5774.220480235199, 5815.389384114607, 1618.902865828054, 1621.9270132844051, 1623.7402015677435, 1629.274972637165, 1641.5157528529112, 1165.2658824940233, 1199.9700007499882, 1216.7019080325376, 1234.3026190202686, 1238.585074000202, 4659.743714095689, 4875.293082503017, 4887.755612219375, 4982.953579748293, 5395.81114660985, 4958.752062396866, 5442.510174084351, 5622.437756224426, 5991.4907232884825, 5998.760049598054, 4528.003839155381, 4877.634262406532, 5260.658057226268, 5394.91907621386, 5410.107332290128, 2757.4485102979484, 2760.674876059503, 3113.8634398444856, 3123.4065527550147, 3125.3279495469815, 5771.364040654361, 5929.347771745104, 5983.461488466059, 6193.597416167935, 6225.657588832658, 5972.970135149297, 6082.817515474543, 6188.628682279078, 6194.469152582985, 6208.348123447064, 5534.529564987021, 6066.42273009012, 6194.845128871814, 6242.28213755424, 6245.5628106033055, 5957.285125784933, 5986.640801551921, 6014.609050411709, 6049.727762250797, 6152.107944347955, 5560.471755183251, 5679.346875109415, 5944.740998420572, 5972.4770183602905, 5973.731182096729, 5454.645448045864, 6264.40257734293, 6344.746210151542, 6376.840578985377, 6493.577917435352, 4549.385832912514, 4603.953960460352, 4708.681569160641, 4748.358971538798, 4749.786259618257, 4983.5763960063805, 5223.608229382888, 5863.00328944072, 6000.939990600038, 6036.336003039574, 5119.539241468286, 5150.91830715539, 5843.649381036981, 5851.64890106595, 5885.823425297248, 4955.752212389294, 5316.680999140064, 5499.230107784993, 5728.448362015577, 5791.739371728367, 5533.474319939599, 5675.602707810413, 5725.541956643375, 5736.6558006519745, 5790.536757059509, 5619.780186826099, 5640.294963129609, 5645.40723224072, 5892.911606325994, 5937.970310148422, 5014.97193075426, 5364.275822763877, 5378.515933565998, 5678.74445649955, 5710.971445142748], "value": 4401.966768135142, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [745.3776096959039, 761.8742907420273, 808.4583329169456, 863.5552690364464, 889.1641856654742, 16375.590610234734, 17611.471655850313, 17617.82382176178, 17639.0, 17688.115594220286, 16149.83850161498, 16261.9429737067, 16346.591335216626, 16363.918180409111, 16400.179991000463, 974.7027156717216, 987.5161171026217, 988.7676396046924, 991.5438898106879, 992.5384696116305, 1014.371089924248, 1015.629295307215, 1024.4672770159514, 1026.1072866606025, 1027.1115485105404, 1049.9947500262488, 1051.4742628685658, 1055.6199768083484, 1068.1401471815186, 1068.2415485005656, 1063.3991794636083, 1063.5107850388792, 1063.9893601064045, 1063.9946800266027, 1064.09551880901, 787.0476723164977, 787.0987719061642, 787.5196130360475, 788.1606089514648, 788.8934993775881, 17551.473455796346, 17842.46472605824, 17850.375236866777, 18265.543361415937, 18527.073646317764, 14723.631909202355, 14935.402583896632, 14963.728083112948, 15001.249937503082, 15190.392384304614, 11076.2365191521, 11101.888981110167, 11146.331220126818, 11336.943315283454, 11566.710832229177, 3428.9485657714904, 3762.5484941806885, 3908.667763240128, 4011.3782363733667, 4064.1668457966343, 90688.0, 94191.0, 111447.0, 112127.87872121256, 113004.43497782541, 10603.522841472151, 11549.826752598718, 11553.36456494884, 11657.242279251821, 11704.648860534198, 24094.51810963771, 24127.15554955603, 24231.39421514441, 24290.14984475535, 24300.27099187027, 7673.731419400294, 7722.266384693445, 7887.9605601971625, 7895.565743884142, 8048.758537243892, 3884.6698030667144, 4644.465886423037, 4651.418572678415, 4660.790264438108, 4689.20283551797, 3498.2478767065204, 3630.909227269339, 3688.704903607705, 3698.5007024051947, 3702.204026134394, 595.2499850188793, 677.0419855903841, 678.0541145102554, 680.2449281297755, 693.9791806245821, 691.94810389221, 703.2967032967033, 703.4126504368807, 726.5168662839153, 746.3544034410196, 10508.264421490423, 10656.573737050587, 10670.466476676134, 11163.553457861757, 11166.665000050012, 649.8667773106501, 714.5962531169872, 736.14607055815, 749.962501874915, 758.4993904023318, 4983.9252411213865, 5214.809223985529, 5239.1093514102695, 5244.239585260192, 5290.68255904647, 107017.0, 108122.0, 110404.34393484105, 110493.0, 110573.89426105635, 84696.15303847006, 96733.0, 97460.02539974652, 99803.0, 100133.49933250288, 64870.67564662241, 68819.55902204869, 83162.0, 83444.58277708573, 83623.16376836154, 8226.0, 9065.637374504946, 9662.08210220028, 9867.40795552269, 10141.645042423776, 45426.0, 47426.05147897092, 51054.744726276134, 54789.178162326796, 55818.72090639521, 69533.65233173802, 69991.60016799533, 71149.0, 71471.21321966992, 72610.27389726035, 11155.442227888736, 11283.717907052061, 11577.0, 11621.0, 11666.76666466683, 11434.37110958872, 11862.525498979945, 11968.281903085843, 11988.880111199125, 11990.220635658654, 12249.510019599473, 12610.180338277982, 12640.115191936844, 12857.421416036113, 12939.158954667917, 12344.75310493803, 12681.682957926125, 12786.552470663482, 12937.547185848827, 13307.26810025458, 11461.197716159788, 11940.223885447418, 12094.818577721166, 12355.876441235472, 12484.625461236177, 9191.540422978958, 9548.57031433601, 9645.065171369262, 9818.263630227904, 10245.590176392861, 9214.723558293263, 10216.846747298649, 10460.163186945178, 10754.569817207537, 10763.0], "value": 7790.844359959226, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1549.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 119.0, 128.0, 168.0, 125.0, 122.0, 120.0, 119.0, 120.0, 120.0], "value": 124.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 108.0, 120.0, 140.0, 111.0, 108.0, 120.0, 135.0, 137.0, 128.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [253.0, 198.0, 256.0, 196.0, 237.0, 200.0, 193.0, 198.0, 194.0, 195.0], "value": 212.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 62.0, 95.0, 61.0, 55.0, 71.0, 84.0, 67.0, 70.0], "value": 70.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 71.0, 84.0, 70.0, 73.0, 70.0, 80.0, 71.0, 68.0, 92.0], "value": 75.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 132.0, 90.0, 90.0, 90.0, 102.0, 91.0, 91.0, 90.0, 96.0], "value": 96.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 97.0, 97.0, 98.0, 116.0, 117.0, 97.0, 97.0], "value": 101.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 85.0, 143.0, 149.0, 88.0, 138.0, 141.0, 89.0, 139.0, 140.0], "value": 122.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 77.0, 67.0, 64.0, 65.0, 64.0, 64.0, 64.0, 67.0], "value": 66.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 64.0, 61.0, 68.0, 64.0, 58.0, 58.0, 59.0, 60.0, 61.0], "value": 61.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 75.0, 97.0, 101.0, 100.0, 83.0, 85.0, 84.0, 88.0, 95.0], "value": 89.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 133.0, 133.0, 168.0, 134.0, 150.0, 166.0, 196.0, 140.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 190.0, 213.0, 221.0, 186.0, 162.0, 156.0, 165.0, 197.0], "value": 181.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 75.0, 82.0, 87.0, 65.0, 63.0, 64.0, 84.0, 82.0], "value": 72.9, "unit": "score"}]}]} 07:23:28 INFO - Return code: 0 07:23:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:23:28 ERROR - # TBPL SUCCESS # 07:23:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:23:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:23:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:23:28 INFO - 2017-05-26 07:23:28.312 screenresolution[1662:10703] starting screenresolution argv=screenresolution get 07:23:28 INFO - 2017-05-26 07:23:28.314 screenresolution[1662:10703] Display 0: 1600x1200x32@60 07:23:28 INFO - 2017-05-26 07:23:28.320 screenresolution[1663:10706] starting screenresolution argv=screenresolution list 07:23:28 INFO - Available Modes on Display 0 07:23:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:23:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:23:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:23:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:23:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:23:28 INFO - Intel Iris: 07:23:28 INFO - Chipset Model: Intel Iris 07:23:28 INFO - Type: GPU 07:23:28 INFO - Bus: Built-In 07:23:28 INFO - VRAM (Dynamic, Max): 1536 MB 07:23:28 INFO - Vendor: Intel (0x8086) 07:23:28 INFO - Device ID: 0x0a2e 07:23:28 INFO - Revision ID: 0x0009 07:23:28 INFO - Displays: 07:23:28 INFO - Display: 07:23:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:23:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:23:28 INFO - Display Serial Number: 1600x1200 60 07:23:28 INFO - Main Display: Yes 07:23:28 INFO - Mirror: Off 07:23:28 INFO - Online: Yes 07:23:28 INFO - Rotation: Supported 07:23:28 INFO - Return code: 0 07:23:28 INFO - Running post-action listener: _resource_record_post_action 07:23:28 INFO - [mozharness: 2017-05-26 14:23:28.558043Z] Finished run-tests step (success) 07:23:28 INFO - Running post-run listener: _resource_record_post_run 07:23:28 INFO - Total resource usage - Wall time: 1193s; CPU: 55.0%; Read bytes: 40065024; Write bytes: 425722880; Read time: 253; Write time: 1425 07:23:28 INFO - TinderboxPrint: CPU usage
54.5% 07:23:28 INFO - TinderboxPrint: I/O read bytes / time
40,065,024 / 253 07:23:28 INFO - TinderboxPrint: I/O write bytes / time
425,722,880 / 1,425 07:23:28 INFO - TinderboxPrint: CPU idle
2,170.9 (45.5%) 07:23:28 INFO - TinderboxPrint: CPU system
960.0 (20.1%) 07:23:28 INFO - TinderboxPrint: CPU user
1,642.8 (34.4%) 07:23:28 INFO - TinderboxPrint: Swap in / out
162,734,080 / 0 07:23:28 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119810560; Write bytes: 115197952; Read time: 10880; Write time: 254 07:23:28 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 37955584; Write bytes: 299453440; Read time: 232; Write time: 1133 07:23:29 INFO - Running post-run listener: _upload_blobber_files 07:23:29 INFO - Blob upload gear active. 07:23:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:23:29 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 07:23:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:23:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:23:29 INFO - (blobuploader) - INFO - Open directory for files ... 07:23:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:23:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:23:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:23:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:23:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:23:30 INFO - (blobuploader) - INFO - Done attempting. 07:23:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:23:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:23:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:23:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:23:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:23:31 INFO - (blobuploader) - INFO - Done attempting. 07:23:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:23:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:23:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:23:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:23:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:23:32 INFO - (blobuploader) - INFO - Done attempting. 07:23:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:23:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:23:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:23:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:23:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:23:34 INFO - (blobuploader) - INFO - Done attempting. 07:23:34 INFO - (blobuploader) - INFO - Iteration through files over. 07:23:34 INFO - Return code: 0 07:23:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:23:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:23:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/121d8b241a445cce6c3d7a13bbc0b8ab07d229c2add559528bfa2a81c6306c7d3398bfede3bb9dcb70bb2c6694308ca8bee0396e64a3e5b8e93aa0e0af4d278f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0888a4b1598561f92d88d5f7635fc1f937f515d7b7584a9b9955cbba840bfd759cbfa77896e89d3eacd190f150e45bdcd3fc0133d2bd7f4224e406d5a4994584", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94c7499126c9f2cac6934e3d934ffc417100dc1e37db21363ef8f593f97483a1578b680cbd5edcae94594935e4078212440ea33df1d83a4ac63e9e245f9f8f53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/68c98feb3d1ee54f511e2c8bc9de473bac4781c59139d6b0b6bf12dba3f0d80feff4d388e2f9c1eba2b129a9a5c28cc92351e3ad7b27dd1693fa087c0361ea0b"} 07:23:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:23:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:23:34 INFO - Contents: 07:23:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/121d8b241a445cce6c3d7a13bbc0b8ab07d229c2add559528bfa2a81c6306c7d3398bfede3bb9dcb70bb2c6694308ca8bee0396e64a3e5b8e93aa0e0af4d278f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0888a4b1598561f92d88d5f7635fc1f937f515d7b7584a9b9955cbba840bfd759cbfa77896e89d3eacd190f150e45bdcd3fc0133d2bd7f4224e406d5a4994584", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94c7499126c9f2cac6934e3d934ffc417100dc1e37db21363ef8f593f97483a1578b680cbd5edcae94594935e4078212440ea33df1d83a4ac63e9e245f9f8f53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/68c98feb3d1ee54f511e2c8bc9de473bac4781c59139d6b0b6bf12dba3f0d80feff4d388e2f9c1eba2b129a9a5c28cc92351e3ad7b27dd1693fa087c0361ea0b"} 07:23:34 INFO - Running post-run listener: copy_logs_to_upload_dir 07:23:34 INFO - Copying logs to upload dir... 07:23:34 INFO - mkdir: /builds/slave/test/build/upload/logs 07:23:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.459491 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-05-26 07:23:34.342160) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 07:23:34.345623) ========= 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.tFvcbk0W4q/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.1ns17MAjmE/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/121d8b241a445cce6c3d7a13bbc0b8ab07d229c2add559528bfa2a81c6306c7d3398bfede3bb9dcb70bb2c6694308ca8bee0396e64a3e5b8e93aa0e0af4d278f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0888a4b1598561f92d88d5f7635fc1f937f515d7b7584a9b9955cbba840bfd759cbfa77896e89d3eacd190f150e45bdcd3fc0133d2bd7f4224e406d5a4994584", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94c7499126c9f2cac6934e3d934ffc417100dc1e37db21363ef8f593f97483a1578b680cbd5edcae94594935e4078212440ea33df1d83a4ac63e9e245f9f8f53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/68c98feb3d1ee54f511e2c8bc9de473bac4781c59139d6b0b6bf12dba3f0d80feff4d388e2f9c1eba2b129a9a5c28cc92351e3ad7b27dd1693fa087c0361ea0b"} build_url:https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011230 build_url: 'https://queue.taskcluster.net/v1/task/Vn2nP-sGTJekzYYjh5wuUg/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/121d8b241a445cce6c3d7a13bbc0b8ab07d229c2add559528bfa2a81c6306c7d3398bfede3bb9dcb70bb2c6694308ca8bee0396e64a3e5b8e93aa0e0af4d278f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0888a4b1598561f92d88d5f7635fc1f937f515d7b7584a9b9955cbba840bfd759cbfa77896e89d3eacd190f150e45bdcd3fc0133d2bd7f4224e406d5a4994584", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94c7499126c9f2cac6934e3d934ffc417100dc1e37db21363ef8f593f97483a1578b680cbd5edcae94594935e4078212440ea33df1d83a4ac63e9e245f9f8f53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/68c98feb3d1ee54f511e2c8bc9de473bac4781c59139d6b0b6bf12dba3f0d80feff4d388e2f9c1eba2b129a9a5c28cc92351e3ad7b27dd1693fa087c0361ea0b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 07:23:34.379393) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 07:23:34.379732) ========= 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.tFvcbk0W4q/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.1ns17MAjmE/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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.005091 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 07:23:34.428509) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 07:23:34.428836) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 07:23:34.429217) ========= ========= Total master_lag: 0.17 =========