builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0356 starttime: 1497541346.08 results: success (0) buildid: 20170615070049 builduid: faafd9045d2c44cdb99f7dd1aee22c53 revision: 9e20e9175de1bbcea33b9b67be64d3734156bae4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-15 08:42:26.077328) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-15 08:42:26.077797) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-15 08:42:26.078140) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-15 08:42:26.103137) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 08:42:26.103654) ========= 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.Rym29ckRQ5/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.eBxQtSQksp/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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-06-15 08:42:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 34.7M=0s 2017-06-15 08:42:26 (34.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131325 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 08:42:26.257686) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 08:42:26.258081) ========= 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.Rym29ckRQ5/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.eBxQtSQksp/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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.026682 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 08:42:26.306210) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-15 08:42:26.306586) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9e20e9175de1bbcea33b9b67be64d3734156bae4 --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 9e20e9175de1bbcea33b9b67be64d3734156bae4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Rym29ckRQ5/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.eBxQtSQksp/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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-06-15 08:42:26,404 truncating revision to first 12 chars 2017-06-15 08:42:26,404 Setting DEBUG logging. 2017-06-15 08:42:26,405 attempt 1/10 2017-06-15 08:42:26,405 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9e20e9175de1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-15 08:42:28,523 unpacking tar archive at: mozilla-beta-9e20e9175de1/testing/mozharness/ program finished with exit code 0 elapsedTime=2.411819 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-15 08:42:28.740809) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-15 08:42:28.741133) ========= script_repo_revision: 9e20e9175de1bbcea33b9b67be64d3734156bae4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-15 08:42:28.741533) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-15 08:42:28.741798) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-15 08:42:28.759480) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-15 08:42:28.759762) ========= /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.Rym29ckRQ5/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.eBxQtSQksp/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:42:28 INFO - MultiFileLogger online at 20170615 08:42:28 in /builds/slave/test 08:42:28 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 08:42:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:42:28 INFO - {'append_to_log': False, 08:42:28 INFO - 'base_work_dir': '/builds/slave/test', 08:42:28 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:42:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:42:28 INFO - 'branch': 'Mozilla-Beta', 08:42:28 INFO - 'buildbot_json_path': 'buildprops.json', 08:42:28 INFO - 'config_files': ('talos/mac_config.py',), 08:42:28 INFO - 'default_actions': ('clobber', 08:42:28 INFO - 'read-buildbot-config', 08:42:28 INFO - 'download-and-extract', 08:42:28 INFO - 'populate-webroot', 08:42:28 INFO - 'create-virtualenv', 08:42:28 INFO - 'install', 08:42:28 INFO - 'setup-mitmproxy', 08:42:28 INFO - 'run-tests'), 08:42:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:42:28 INFO - 'download_minidump_stackwalk': True, 08:42:28 INFO - 'download_symbols': 'ondemand', 08:42:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:42:28 INFO - 'tooltool.py': '/tools/tooltool.py', 08:42:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:42:28 INFO - '/tools/misc-python/virtualenv.py')}, 08:42:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:42:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:42:28 INFO - 'gecko_profile': False, 08:42:28 INFO - 'gecko_profile_interval': 0, 08:42:28 INFO - 'installer_path': 'installer.exe', 08:42:28 INFO - 'log_level': 'info', 08:42:28 INFO - 'log_name': 'talos', 08:42:28 INFO - 'log_to_console': True, 08:42:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:42:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:42:28 INFO - 'opt_config_files': (), 08:42:28 INFO - 'pip_index': False, 08:42:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:42:28 INFO - 'cmd': ('bash', 08:42:28 INFO - '-c', 08:42:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:42:28 INFO - 'enabled': True, 08:42:28 INFO - 'halt_on_failure': False, 08:42:28 INFO - 'name': 'check_screen_resolution'},), 08:42:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:42:28 INFO - 'cmd': ('bash', 08:42:28 INFO - '-c', 08:42:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:42:28 INFO - 'enabled': True, 08:42:28 INFO - 'halt_on_failure': False, 08:42:28 INFO - 'name': 'check_screen_resolution'},), 08:42:28 INFO - 'run_cmd_checks_enabled': True, 08:42:28 INFO - 'suite': 'dromaeojs-e10s', 08:42:28 INFO - 'system_bits': '32', 08:42:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:42:28 INFO - 'title': 't-yosemite-r7-0356', 08:42:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:42:28 INFO - 'use_talos_json': True, 08:42:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:42:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:42:28 INFO - 'work_dir': 'build'} 08:42:28 INFO - [mozharness: 2017-06-15 15:42:28.978158Z] Running clobber step. 08:42:28 INFO - Running pre-action listener: _resource_record_pre_action 08:42:28 INFO - Running main action method: clobber 08:42:28 INFO - rmtree: /builds/slave/test/build 08:42:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:42:31 INFO - Running post-action listener: _resource_record_post_action 08:42:31 INFO - [mozharness: 2017-06-15 15:42:31.368074Z] Finished clobber step (success) 08:42:31 INFO - [mozharness: 2017-06-15 15:42:31.368217Z] Running read-buildbot-config step. 08:42:31 INFO - Running pre-action listener: _resource_record_pre_action 08:42:31 INFO - Running main action method: read_buildbot_config 08:42:31 INFO - Using buildbot properties: 08:42:31 INFO - { 08:42:31 INFO - "project": "", 08:42:31 INFO - "product": "firefox", 08:42:31 INFO - "script_repo_revision": "production", 08:42:31 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 08:42:31 INFO - "repository": "", 08:42:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 08:42:31 INFO - "buildid": "20170615070049", 08:42:31 INFO - "pgo_build": "False", 08:42:31 INFO - "basedir": "/builds/slave/test", 08:42:31 INFO - "buildnumber": 4, 08:42:31 INFO - "slavename": "t-yosemite-r7-0356", 08:42:31 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:42:31 INFO - "platform": "yosemite_r7_devedition", 08:42:31 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 08:42:31 INFO - "branch": "mozilla-beta", 08:42:31 INFO - "repo_path": "releases/mozilla-beta", 08:42:31 INFO - "revision": "9e20e9175de1bbcea33b9b67be64d3734156bae4", 08:42:31 INFO - "stage_platform": "macosx64-devedition", 08:42:31 INFO - "builduid": "faafd9045d2c44cdb99f7dd1aee22c53", 08:42:31 INFO - "slavebuilddir": "test" 08:42:31 INFO - } 08:42:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 08:42:31 INFO - Running post-action listener: _resource_record_post_action 08:42:31 INFO - [mozharness: 2017-06-15 15:42:31.371409Z] Finished read-buildbot-config step (success) 08:42:31 INFO - [mozharness: 2017-06-15 15:42:31.371531Z] Running download-and-extract step. 08:42:31 INFO - Running pre-action listener: _resource_record_pre_action 08:42:31 INFO - Running main action method: download_and_extract 08:42:31 INFO - mkdir: /builds/slave/test/build/tests 08:42:31 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')]} 08:42:31 INFO - trying https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 08:42:31 INFO - Downloading https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 08:42:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 08:42:31 INFO - Downloaded 1290 bytes. 08:42:31 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 08:42:31 INFO - Using the following test package requirements: 08:42:31 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:42:31 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 08:42:31 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 08:42:31 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:42:31 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 08:42:31 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:42:31 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 08:42:31 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:42:31 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:42:31 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 08:42:31 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 08:42:31 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:42:31 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 08:42:31 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:42:31 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 08:42:31 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:42:31 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 08:42:31 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:42:31 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 08:42:31 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 08:42:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 08:42:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 08:42:31 INFO - Fetch https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 08:42:33 INFO - Content-Length response header: 59550192 08:42:33 INFO - Bytes received: 59550192 08:42:51 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 08:42:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 08:42:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 08:42:51 INFO - Fetch https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 08:42:53 INFO - Content-Length response header: 59550192 08:42:53 INFO - Bytes received: 59550192 08:43:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 08:43:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 08:43:11 INFO - Fetch https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 08:43:12 INFO - Content-Length response header: 14454473 08:43:12 INFO - Bytes received: 14454473 08:43: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')]} 08:43:12 INFO - trying https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 08:43:12 INFO - Downloading https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 08:43:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 08:43:15 INFO - Downloaded 66870735 bytes. 08:43:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 08:43:15 INFO - mkdir: /builds/slave/test/properties 08:43:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:43:15 INFO - Writing to file /builds/slave/test/properties/build_url 08:43:15 INFO - Contents: 08:43:15 INFO - build_url:https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 08:43:16 INFO - Running post-action listener: _resource_record_post_action 08:43:16 INFO - Running post-action listener: set_extra_try_arguments 08:43:16 INFO - [mozharness: 2017-06-15 15:43:16.007492Z] Finished download-and-extract step (success) 08:43:16 INFO - [mozharness: 2017-06-15 15:43:16.007619Z] Running populate-webroot step. 08:43:16 INFO - Running pre-action listener: _resource_record_pre_action 08:43:16 INFO - Running main action method: populate_webroot 08:43:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:43:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:43:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:43:16 INFO - '--tppagecycles', 08:43:16 INFO - '7'], 08:43:16 INFO - 'tests': ['tsvgm']}}, 08:43:16 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:43:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:43:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:43:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:43:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:43:16 INFO - 'talos_options': ['--disable-e10s'], 08:43:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:43:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:43:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:43:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:43:16 INFO - 'talos_options': ['--disable-e10s'], 08:43:16 INFO - 'tests': ['damp', 'tps']}, 08:43:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:43:16 INFO - 'tests': ['damp', 'tps']}, 08:43:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:43:16 INFO - 'tests': ['dromaeo_dom']}, 08:43:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:43:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:43:16 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:43:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:43:16 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:43:16 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:43:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:43:16 INFO - 'tests': ['a11yr', 08:43:16 INFO - 'ts_paint', 08:43:16 INFO - 'tpaint', 08:43:16 INFO - 'sessionrestore', 08:43:16 INFO - 'sessionrestore_no_auto_restore', 08:43:16 INFO - 'tabpaint']}, 08:43:16 INFO - 'other-e10s': {'tests': ['a11yr', 08:43:16 INFO - 'ts_paint', 08:43:16 INFO - 'tpaint', 08:43:16 INFO - 'sessionrestore', 08:43:16 INFO - 'sessionrestore_no_auto_restore', 08:43:16 INFO - 'tabpaint']}, 08:43:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:43:16 INFO - 'ts_paint', 08:43:16 INFO - 'tpaint', 08:43:16 INFO - 'sessionrestore', 08:43:16 INFO - 'sessionrestore_no_auto_restore', 08:43:16 INFO - 'tabpaint']}, 08:43:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:43:16 INFO - 'ts_paint', 08:43:16 INFO - 'tpaint', 08:43:16 INFO - 'sessionrestore', 08:43:16 INFO - 'sessionrestore_no_auto_restore', 08:43:16 INFO - 'tabpaint']}, 08:43:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:43:16 INFO - 'tests': ['a11yr', 08:43:16 INFO - 'ts_paint', 08:43:16 INFO - 'tpaint', 08:43:16 INFO - 'sessionrestore', 08:43:16 INFO - 'sessionrestore_no_auto_restore', 08:43:16 INFO - 'tabpaint']}, 08:43:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:43:16 INFO - 'tests': ['a11yr', 08:43:16 INFO - 'ts_paint', 08:43:16 INFO - 'tpaint', 08:43:16 INFO - 'sessionrestore', 08:43:16 INFO - 'sessionrestore_no_auto_restore', 08:43:16 INFO - 'tabpaint']}, 08:43:16 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:43:16 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:43:16 INFO - 'bloom_basic_ref']}, 08:43:16 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:43:16 INFO - 'talos_options': ['--mitmproxy', 08:43:16 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:43:16 INFO - '--firstNonBlankPaint'], 08:43:16 INFO - 'tests': ['quantum_pageload_google', 08:43:16 INFO - 'quantum_pageload_youtube', 08:43:16 INFO - 'quantum_pageload_amazon', 08:43:16 INFO - 'quantum_pageload_facebook']}, 08:43:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:43:16 INFO - 'tests': ['tsvgx', 08:43:16 INFO - 'tsvgr_opacity', 08:43:16 INFO - 'tart', 08:43:16 INFO - 'tscrollx', 08:43:16 INFO - 'cart', 08:43:16 INFO - 'tsvg_static']}, 08:43:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:43:16 INFO - 'tsvgr_opacity', 08:43:16 INFO - 'tart', 08:43:16 INFO - 'tscrollx', 08:43:16 INFO - 'cart', 08:43:16 INFO - 'tsvg_static']}, 08:43:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:43:16 INFO - 'talos_options': ['--disable-e10s'], 08:43:16 INFO - 'tests': ['tp5o']}, 08:43:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:43:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:43:16 INFO - 'talos_options': ['--disable-e10s', 08:43:16 INFO - '--xperf_path', 08:43:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:43:16 INFO - 'tests': ['tp5n']}, 08:43:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:43:16 INFO - 'talos_options': ['--xperf_path', 08:43:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:43:16 INFO - 'tests': ['tp5n']}}, 08:43:16 INFO - 'talos.zip': {'path': '', 08:43:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:43:16 INFO - Running post-action listener: _resource_record_post_action 08:43:16 INFO - [mozharness: 2017-06-15 15:43:16.027779Z] Finished populate-webroot step (success) 08:43:16 INFO - [mozharness: 2017-06-15 15:43:16.027887Z] Running create-virtualenv step. 08:43:16 INFO - Running pre-action listener: _resource_record_pre_action 08:43:16 INFO - Running main action method: create_virtualenv 08:43:16 INFO - Creating virtualenv /builds/slave/test/build/venv 08:43:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:43:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:43:16 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:43:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:43:16 INFO - Using real prefix '/tools/python27' 08:43:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:43:16 INFO - Installing distribute.............................................................................................................................................................................................done. 08:43:19 INFO - Installing pip.................done. 08:43:19 INFO - Return code: 0 08:43:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:43:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:43:19 INFO - Reading from file tmpfile_stdout 08:43:19 INFO - Output received: 08:43:19 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:43:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:43:19 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')]} 08:43:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:19 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')]} 08:43:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:20 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 0x109b45c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b49768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefb4de3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10989bd30>, '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 0x109865cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10988cbe8>, '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.Rym29ckRQ5/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.eBxQtSQksp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:20 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 08:43:20 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 08:43:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rym29ckRQ5/Render', 08:43:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:20 INFO - 'HOME': '/Users/cltbld', 08:43:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:20 INFO - 'LOGNAME': 'cltbld', 08:43:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:20 INFO - 'MOZ_NO_REMOTE': '1', 08:43:20 INFO - 'NO_EM_RESTART': '1', 08:43:20 INFO - 'PAGER': '/bin/cat', 08:43:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:20 INFO - 'PWD': '/builds/slave/test', 08:43:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:20 INFO - 'SHELL': '/bin/bash', 08:43:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eBxQtSQksp/Listeners', 08:43:20 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 08:43:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:20 INFO - 'USER': 'cltbld', 08:43:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:20 INFO - 'XPC_FLAGS': '0x0', 08:43:20 INFO - 'XPC_SERVICE_NAME': '0', 08:43:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:20 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:43:20 INFO - Cleaning up... 08:43:20 INFO - Return code: 0 08:43:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:43:20 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')]} 08:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:20 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')]} 08:43:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:20 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 0x109b45c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b49768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefb4de3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10989bd30>, '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 0x109865cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10988cbe8>, '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.Rym29ckRQ5/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.eBxQtSQksp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:20 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 08:43:20 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 08:43:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rym29ckRQ5/Render', 08:43:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:20 INFO - 'HOME': '/Users/cltbld', 08:43:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:20 INFO - 'LOGNAME': 'cltbld', 08:43:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:20 INFO - 'MOZ_NO_REMOTE': '1', 08:43:20 INFO - 'NO_EM_RESTART': '1', 08:43:20 INFO - 'PAGER': '/bin/cat', 08:43:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:20 INFO - 'PWD': '/builds/slave/test', 08:43:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:20 INFO - 'SHELL': '/bin/bash', 08:43:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eBxQtSQksp/Listeners', 08:43:20 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 08:43:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:20 INFO - 'USER': 'cltbld', 08:43:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:20 INFO - 'XPC_FLAGS': '0x0', 08:43:20 INFO - 'XPC_SERVICE_NAME': '0', 08:43:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:20 INFO - Downloading/unpacking psutil>=3.1.1 08:43: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 08:43: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 08:43: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 08:43: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 08:43:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:43:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:43:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:43:22 INFO - Installing collected packages: psutil 08:43:22 INFO - Running setup.py install for psutil 08:43:22 INFO - building 'psutil._psutil_osx' extension 08:43:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:43:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:43:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:43:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:43:23 INFO - building 'psutil._psutil_posix' extension 08:43:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:43:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:43:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:43:23 INFO - ^ 08:43:23 INFO - 1 warning generated. 08:43:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:43:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:43:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:43:23 INFO - Successfully installed psutil 08:43:23 INFO - Cleaning up... 08:43:23 INFO - Return code: 0 08:43:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:43:23 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')]} 08:43:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:23 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')]} 08:43:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x109b45c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b49768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefb4de3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10989bd30>, '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 0x109865cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10988cbe8>, '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.Rym29ckRQ5/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.eBxQtSQksp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:24 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 08:43:24 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 08:43:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rym29ckRQ5/Render', 08:43:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:24 INFO - 'HOME': '/Users/cltbld', 08:43:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:24 INFO - 'LOGNAME': 'cltbld', 08:43:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:24 INFO - 'MOZ_NO_REMOTE': '1', 08:43:24 INFO - 'NO_EM_RESTART': '1', 08:43:24 INFO - 'PAGER': '/bin/cat', 08:43:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:24 INFO - 'PWD': '/builds/slave/test', 08:43:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:24 INFO - 'SHELL': '/bin/bash', 08:43:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eBxQtSQksp/Listeners', 08:43:24 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 08:43:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:24 INFO - 'USER': 'cltbld', 08:43:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:24 INFO - 'XPC_FLAGS': '0x0', 08:43:24 INFO - 'XPC_SERVICE_NAME': '0', 08:43:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:43: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 08:43: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 08:43: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 08:43: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 08:43:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:43:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:43:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:43:26 INFO - Installing collected packages: mozsystemmonitor 08:43:26 INFO - Running setup.py install for mozsystemmonitor 08:43:26 INFO - Successfully installed mozsystemmonitor 08:43:26 INFO - Cleaning up... 08:43:26 INFO - Return code: 0 08:43:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:43:26 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')]} 08:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:26 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')]} 08:43:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:26 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 0x109b45c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b49768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefb4de3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10989bd30>, '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 0x109865cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10988cbe8>, '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.Rym29ckRQ5/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.eBxQtSQksp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:26 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 08:43:26 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 08:43:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rym29ckRQ5/Render', 08:43:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:26 INFO - 'HOME': '/Users/cltbld', 08:43:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:26 INFO - 'LOGNAME': 'cltbld', 08:43:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:26 INFO - 'MOZ_NO_REMOTE': '1', 08:43:26 INFO - 'NO_EM_RESTART': '1', 08:43:26 INFO - 'PAGER': '/bin/cat', 08:43:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:26 INFO - 'PWD': '/builds/slave/test', 08:43:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:26 INFO - 'SHELL': '/bin/bash', 08:43:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eBxQtSQksp/Listeners', 08:43:26 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 08:43:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:26 INFO - 'USER': 'cltbld', 08:43:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:26 INFO - 'XPC_FLAGS': '0x0', 08:43:26 INFO - 'XPC_SERVICE_NAME': '0', 08:43:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:26 INFO - Downloading/unpacking jsonschema==2.5.1 08:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:43:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:28 INFO - Downloading functools32-3.2.3-2.tar.gz 08:43:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:43:28 INFO - warning: no files found matching '*.txt' 08:43:28 INFO - no previously-included directories found matching 'build' 08:43:28 INFO - no previously-included directories found matching 'dist' 08:43:28 INFO - no previously-included directories found matching '.git*' 08:43:28 INFO - Installing collected packages: jsonschema, functools32 08:43:28 INFO - Running setup.py install for functools32 08:43:28 INFO - warning: no files found matching '*.txt' 08:43:28 INFO - no previously-included directories found matching 'build' 08:43:28 INFO - no previously-included directories found matching 'dist' 08:43:28 INFO - no previously-included directories found matching '.git*' 08:43:28 INFO - Successfully installed jsonschema functools32 08:43:28 INFO - Cleaning up... 08:43:28 INFO - Return code: 0 08:43:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:43:28 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')]} 08:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:28 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')]} 08:43:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:28 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 0x109b45c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b49768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefb4de3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10989bd30>, '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 0x109865cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10988cbe8>, '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.Rym29ckRQ5/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.eBxQtSQksp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:28 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 08:43:28 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 08:43:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rym29ckRQ5/Render', 08:43:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:28 INFO - 'HOME': '/Users/cltbld', 08:43:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:28 INFO - 'LOGNAME': 'cltbld', 08:43:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:28 INFO - 'MOZ_NO_REMOTE': '1', 08:43:28 INFO - 'NO_EM_RESTART': '1', 08:43:28 INFO - 'PAGER': '/bin/cat', 08:43:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:28 INFO - 'PWD': '/builds/slave/test', 08:43:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:28 INFO - 'SHELL': '/bin/bash', 08:43:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eBxQtSQksp/Listeners', 08:43:28 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 08:43:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:28 INFO - 'USER': 'cltbld', 08:43:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:28 INFO - 'XPC_FLAGS': '0x0', 08:43:28 INFO - 'XPC_SERVICE_NAME': '0', 08:43:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:43:29 INFO - Cleaning up... 08:43:29 INFO - Return code: 0 08:43:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:43: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')]} 08:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43: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')]} 08:43:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x109b45c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b49768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefb4de3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10989bd30>, '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 0x109865cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10988cbe8>, '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.Rym29ckRQ5/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.eBxQtSQksp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:29 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 08:43:29 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 08:43:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rym29ckRQ5/Render', 08:43:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:29 INFO - 'HOME': '/Users/cltbld', 08:43:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:29 INFO - 'LOGNAME': 'cltbld', 08:43:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:29 INFO - 'MOZ_NO_REMOTE': '1', 08:43:29 INFO - 'NO_EM_RESTART': '1', 08:43:29 INFO - 'PAGER': '/bin/cat', 08:43:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:29 INFO - 'PWD': '/builds/slave/test', 08:43:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:29 INFO - 'SHELL': '/bin/bash', 08:43:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eBxQtSQksp/Listeners', 08:43:29 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 08:43:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:29 INFO - 'USER': 'cltbld', 08:43:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:29 INFO - 'XPC_FLAGS': '0x0', 08:43:29 INFO - 'XPC_SERVICE_NAME': '0', 08:43:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:29 INFO - Downloading/unpacking blobuploader==1.2.4 08:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:30 INFO - Downloading blobuploader-1.2.4.tar.gz 08:43:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:43:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:43:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:43:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:31 INFO - Downloading docopt-0.6.1.tar.gz 08:43:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:43:31 INFO - Installing collected packages: blobuploader, requests, docopt 08:43:31 INFO - Running setup.py install for blobuploader 08:43:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:43:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:43:31 INFO - Running setup.py install for requests 08:43:32 INFO - Running setup.py install for docopt 08:43:32 INFO - Successfully installed blobuploader requests docopt 08:43:32 INFO - Cleaning up... 08:43:32 INFO - Return code: 0 08:43:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43:32 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')]} 08:43:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:32 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')]} 08:43:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:32 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 0x109b45c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b49768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefb4de3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10989bd30>, '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 0x109865cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10988cbe8>, '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.Rym29ckRQ5/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.eBxQtSQksp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:32 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 08:43:32 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 08:43:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rym29ckRQ5/Render', 08:43:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:32 INFO - 'HOME': '/Users/cltbld', 08:43:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:32 INFO - 'LOGNAME': 'cltbld', 08:43:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:32 INFO - 'MOZ_NO_REMOTE': '1', 08:43:32 INFO - 'NO_EM_RESTART': '1', 08:43:32 INFO - 'PAGER': '/bin/cat', 08:43:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:32 INFO - 'PWD': '/builds/slave/test', 08:43:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:32 INFO - 'SHELL': '/bin/bash', 08:43:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eBxQtSQksp/Listeners', 08:43:32 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 08:43:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:32 INFO - 'USER': 'cltbld', 08:43:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:32 INFO - 'XPC_FLAGS': '0x0', 08:43:32 INFO - 'XPC_SERVICE_NAME': '0', 08:43:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:43:32 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-aba_vu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:43:32 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-3Rs9_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:43:32 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-8rQLfM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:43:33 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-q__uAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:43:33 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-yIugk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:43:33 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-wpFelI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:43:33 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-fzomA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:43:33 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-xqNV1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:43:33 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-JiV_5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:43:33 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-v6vnRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:43:33 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-vTozI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:43:33 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-NDTP5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:43:34 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-Lu86sS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:43:34 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-40byha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:34 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-yhxtJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:43:34 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-fcHS34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:43:34 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-tVlY1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:43:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:43:34 INFO - Running setup.py install for manifestparser 08:43:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:43:34 INFO - Running setup.py install for mozcrash 08:43:34 INFO - Running setup.py install for mozdebug 08:43:34 INFO - Running setup.py install for mozdevice 08:43:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:43:35 INFO - Running setup.py install for mozfile 08:43:35 INFO - Running setup.py install for mozhttpd 08:43:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:43:35 INFO - Running setup.py install for mozinfo 08:43:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:43:35 INFO - Running setup.py install for mozInstall 08:43:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:43:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:43:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:43:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:43:35 INFO - Running setup.py install for mozleak 08:43:35 INFO - Running setup.py install for mozlog 08:43:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:43:35 INFO - Running setup.py install for moznetwork 08:43:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:43:36 INFO - Running setup.py install for mozprocess 08:43:36 INFO - Running setup.py install for mozprofile 08:43:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:43:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:43:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:43:36 INFO - Running setup.py install for mozrunner 08:43:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:43:36 INFO - Running setup.py install for mozscreenshot 08:43:36 INFO - Running setup.py install for moztest 08:43:36 INFO - Running setup.py install for mozversion 08:43:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:43:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:43:37 INFO - Cleaning up... 08:43:37 INFO - Return code: 0 08:43:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43:37 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')]} 08:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:37 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')]} 08:43:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:37 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 0x109b45c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b49768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefb4de3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10989bd30>, '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 0x109865cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10988cbe8>, '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.Rym29ckRQ5/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.eBxQtSQksp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:37 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 08:43:37 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 08:43:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rym29ckRQ5/Render', 08:43:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:37 INFO - 'HOME': '/Users/cltbld', 08:43:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:37 INFO - 'LOGNAME': 'cltbld', 08:43:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:37 INFO - 'MOZ_NO_REMOTE': '1', 08:43:37 INFO - 'NO_EM_RESTART': '1', 08:43:37 INFO - 'PAGER': '/bin/cat', 08:43:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:37 INFO - 'PWD': '/builds/slave/test', 08:43:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:37 INFO - 'SHELL': '/bin/bash', 08:43:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eBxQtSQksp/Listeners', 08:43:37 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 08:43:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:37 INFO - 'USER': 'cltbld', 08:43:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:37 INFO - 'XPC_FLAGS': '0x0', 08:43:37 INFO - 'XPC_SERVICE_NAME': '0', 08:43:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:43:37 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-FjrkgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:43:37 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-Fd5SHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:43:37 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-am7JDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:43:37 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-JM4L_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:43:37 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-k01LaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:43:37 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-fH5SUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:43:37 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-RutxKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:43:38 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-dOIcwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:43:38 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-OyFsqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:43:38 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-6FCfK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:43:38 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-rR4rvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:43:38 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-hfTRVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:43:38 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-wvbmfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:43:38 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-eHvQO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:38 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-C4PMWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:43:38 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-Ld3l88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:43:39 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-sTt6xD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:43:39 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:43:39 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:43:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:43:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:40 INFO - Downloading blessings-1.6.tar.gz 08:43:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:43:40 INFO - Installing collected packages: blessings 08:43:40 INFO - Running setup.py install for blessings 08:43:40 INFO - Successfully installed blessings 08:43:40 INFO - Cleaning up... 08:43:40 INFO - Return code: 0 08:43:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:43:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:43:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:43:41 INFO - Reading from file tmpfile_stdout 08:43:41 INFO - Current package versions: 08:43:41 INFO - blessings == 1.6 08:43:41 INFO - blobuploader == 1.2.4 08:43:41 INFO - docopt == 0.6.1 08:43:41 INFO - functools32 == 3.2.3-2 08:43:41 INFO - jsonschema == 2.5.1 08:43:41 INFO - manifestparser == 1.1 08:43:41 INFO - mozInstall == 1.13 08:43:41 INFO - mozcrash == 1.0 08:43:41 INFO - mozdebug == 0.1 08:43:41 INFO - mozdevice == 0.51 08:43:41 INFO - mozfile == 1.2 08:43:41 INFO - mozhttpd == 0.7 08:43:41 INFO - mozinfo == 0.10 08:43:41 INFO - mozleak == 0.1 08:43:41 INFO - mozlog == 3.4 08:43:41 INFO - moznetwork == 0.27 08:43:41 INFO - mozprocess == 0.25 08:43:41 INFO - mozprofile == 0.28 08:43:41 INFO - mozrunner == 6.13 08:43:41 INFO - mozscreenshot == 0.1 08:43:41 INFO - mozsystemmonitor == 0.3 08:43:41 INFO - moztest == 0.8 08:43:41 INFO - mozversion == 1.4 08:43:41 INFO - psutil == 3.1.1 08:43:41 INFO - requests == 1.2.3 08:43:41 INFO - wsgiref == 0.1.2 08:43:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43:41 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')]} 08:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:41 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')]} 08:43:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:41 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 0x109b45c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b49768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefb4de3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10989bd30>, '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 0x109865cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10988cbe8>, '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.Rym29ckRQ5/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.eBxQtSQksp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:41 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 08:43:41 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 08:43:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rym29ckRQ5/Render', 08:43:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:41 INFO - 'HOME': '/Users/cltbld', 08:43:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:41 INFO - 'LOGNAME': 'cltbld', 08:43:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:41 INFO - 'MOZ_NO_REMOTE': '1', 08:43:41 INFO - 'NO_EM_RESTART': '1', 08:43:41 INFO - 'PAGER': '/bin/cat', 08:43:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:41 INFO - 'PWD': '/builds/slave/test', 08:43:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:41 INFO - 'SHELL': '/bin/bash', 08:43:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eBxQtSQksp/Listeners', 08:43:41 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 08:43:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:41 INFO - 'USER': 'cltbld', 08:43:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:41 INFO - 'XPC_FLAGS': '0x0', 08:43:41 INFO - 'XPC_SERVICE_NAME': '0', 08:43:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:43:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:43:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:43:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:43:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:43:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:43:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:43:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:43:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:43:41 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:42 INFO - Downloading marionette_driver-1.4.0.tar.gz 08:43:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 08:43:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:43:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:43:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:43:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:43:42 INFO - Installing collected packages: marionette-driver 08:43:42 INFO - Running setup.py install for marionette-driver 08:43:43 INFO - Successfully installed marionette-driver 08:43:43 INFO - Cleaning up... 08:43:43 INFO - Return code: 0 08:43:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:43:43 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')]} 08:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:43 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')]} 08:43:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:43 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 0x109b45c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b49768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbefb4de3d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10989bd30>, '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 0x109865cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10988cbe8>, '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.Rym29ckRQ5/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.eBxQtSQksp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:43 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 08:43:43 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 08:43:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rym29ckRQ5/Render', 08:43:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:43 INFO - 'HOME': '/Users/cltbld', 08:43:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:43 INFO - 'LOGNAME': 'cltbld', 08:43:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:43 INFO - 'MOZ_NO_REMOTE': '1', 08:43:43 INFO - 'NO_EM_RESTART': '1', 08:43:43 INFO - 'PAGER': '/bin/cat', 08:43:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:43 INFO - 'PWD': '/builds/slave/test', 08:43:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:43 INFO - 'SHELL': '/bin/bash', 08:43:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eBxQtSQksp/Listeners', 08:43:43 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 08:43:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:43 INFO - 'USER': 'cltbld', 08:43:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:43 INFO - 'XPC_FLAGS': '0x0', 08:43:43 INFO - 'XPC_SERVICE_NAME': '0', 08:43:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:43:43 INFO - Cleaning up... 08:43:43 INFO - Return code: 0 08:43:43 INFO - Running post-action listener: _resource_record_post_action 08:43:43 INFO - Running post-action listener: _start_resource_monitoring 08:43:43 INFO - Starting resource monitoring. 08:43:43 INFO - [mozharness: 2017-06-15 15:43:43.403126Z] Finished create-virtualenv step (success) 08:43:43 INFO - [mozharness: 2017-06-15 15:43:43.404117Z] Running install step. 08:43:43 INFO - Running pre-action listener: _resource_record_pre_action 08:43:43 INFO - Running main action method: install 08:43:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:43:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:43:43 INFO - Reading from file tmpfile_stdout 08:43:43 INFO - Detecting whether we're running mozinstall >=1.0... 08:43:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:43:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:43:43 INFO - Reading from file tmpfile_stdout 08:43:43 INFO - Output received: 08:43:43 INFO - Usage: mozinstall [options] installer 08:43:43 INFO - Options: 08:43:43 INFO - -h, --help show this help message and exit 08:43:43 INFO - -d DEST, --destination=DEST 08:43:43 INFO - Directory to install application into. [default: 08:43:43 INFO - "/builds/slave/test"] 08:43:43 INFO - --app=APP Application being installed. [default: firefox] 08:43:43 INFO - mkdir: /builds/slave/test/build/application 08:43:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:43:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 08:44:05 INFO - Reading from file tmpfile_stdout 08:44:05 INFO - Output received: 08:44:05 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:44:05 INFO - Running post-action listener: _resource_record_post_action 08:44:05 INFO - [mozharness: 2017-06-15 15:44:05.022065Z] Finished install step (success) 08:44:05 INFO - [mozharness: 2017-06-15 15:44:05.022201Z] Running setup-mitmproxy step. 08:44:05 INFO - Running pre-action listener: _resource_record_pre_action 08:44:05 INFO - Running main action method: setup_mitmproxy 08:44:05 INFO - Skipping: mitmproxy is not required 08:44:05 INFO - Running post-action listener: _resource_record_post_action 08:44:05 INFO - [mozharness: 2017-06-15 15:44:05.022895Z] Finished setup-mitmproxy step (success) 08:44:05 INFO - [mozharness: 2017-06-15 15:44:05.023038Z] Running run-tests step. 08:44:05 INFO - Running pre-action listener: _resource_record_pre_action 08:44:05 INFO - Running main action method: run_tests 08:44:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:44:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:44:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:44:05 INFO - 2017-06-15 08:44:05.037 screenresolution[1625:7329] starting screenresolution argv=screenresolution get 08:44:05 INFO - 2017-06-15 08:44:05.040 screenresolution[1625:7329] Display 0: 1600x1200x32@60 08:44:05 INFO - 2017-06-15 08:44:05.045 screenresolution[1626:7332] starting screenresolution argv=screenresolution list 08:44:05 INFO - Available Modes on Display 0 08:44:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:44:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:44:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:44:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:44:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:44:05 INFO - Intel Iris: 08:44:05 INFO - Chipset Model: Intel Iris 08:44:05 INFO - Type: GPU 08:44:05 INFO - Bus: Built-In 08:44:05 INFO - VRAM (Dynamic, Max): 1536 MB 08:44:05 INFO - Vendor: Intel (0x8086) 08:44:05 INFO - Device ID: 0x0a2e 08:44:05 INFO - Revision ID: 0x0009 08:44:05 INFO - Displays: 08:44:05 INFO - Display: 08:44:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:44:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:44:05 INFO - Display Serial Number: 1600x1200 60 08:44:05 INFO - Main Display: Yes 08:44:05 INFO - Mirror: Off 08:44:05 INFO - Online: Yes 08:44:05 INFO - Rotation: Supported 08:44:05 INFO - Return code: 0 08:44:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:44:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:44:05 INFO - Python 2.7.3 08:44:05 INFO - Return code: 0 08:44:05 INFO - grabbing minidump binary from tooltool 08:44:05 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')]} 08:44:05 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 0x10989bd30>, '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 0x109865cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10988cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:44:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:44:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:44:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:44:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:44:05 INFO - Return code: 0 08:44:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:44:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:44:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:44:05 INFO - ENV: RUST_BACKTRACE is now 1 08:44:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:44:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:44:05 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0356', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:44:05 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0356 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:44:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rym29ckRQ5/Render', 08:44:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:05 INFO - 'HOME': '/Users/cltbld', 08:44:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:05 INFO - 'LOGNAME': 'cltbld', 08:44:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:44:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:44:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:05 INFO - 'MOZ_NO_REMOTE': '1', 08:44:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:44:05 INFO - 'NO_EM_RESTART': '1', 08:44:05 INFO - 'PAGER': '/bin/cat', 08:44:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:05 INFO - 'PWD': '/builds/slave/test', 08:44:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:44:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:05 INFO - 'RUST_BACKTRACE': '1', 08:44:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:44:05 INFO - 'SHELL': '/bin/bash', 08:44:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eBxQtSQksp/Listeners', 08:44:05 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 08:44:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:05 INFO - 'USER': 'cltbld', 08:44:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:05 INFO - 'XPC_FLAGS': '0x0', 08:44:05 INFO - 'XPC_SERVICE_NAME': '0', 08:44:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:05 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0356', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:44:05 INFO - mozversion application_buildid: 20170615070049 08:44:05 INFO - mozversion application_changeset: 9e20e9175de1bbcea33b9b67be64d3734156bae4 08:44:05 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:44:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:44:05 INFO - mozversion application_name: Firefox 08:44:05 INFO - mozversion application_remotingname: firefox-dev 08:44:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:44:05 INFO - mozversion application_vendor: Mozilla 08:44:05 INFO - mozversion application_version: 55.0 08:44:05 INFO - mozversion platform_buildid: 20170615070049 08:44:05 INFO - mozversion platform_changeset: 9e20e9175de1bbcea33b9b67be64d3734156bae4 08:44:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:44:05 INFO - mozversion platform_version: 55.0 08:44:05 INFO - using testdate: 1497541445 08:44:05 INFO - actual date: 1497541445 08:44:05 INFO - starting webserver on 'localhost:49229' 08:44:05 INFO - SUITE-START | Running 2 tests 08:44:05 INFO - TEST-START | dromaeo_css 08:44:05 INFO - operating with platform_type : mac_ 08:44:05 INFO - Initialising browser for dromaeo_css test... 08:44:05 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmp5chxid/profile 08:44:05 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 08:44:38 INFO - TEST-INFO | 1636: killed by SIGTERM 08:44:38 INFO - Browser initialized. 08:44:38 INFO - Running cycle 1/1 for dromaeo_css test... 08:44:38 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmp5chxid/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:44:40 INFO - PID 1645 | 2017-06-15 08:44:40.690 plugin-container[1646:7877] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x974b, name = 'com.apple.tsm.portname' 08:44:40 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:40 INFO - PID 1645 | 2017-06-15 08:44:40.690 plugin-container[1646:7877] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0f, name = 'com.apple.CFPasteboardClient' 08:44:40 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:40 INFO - PID 1645 | 2017-06-15 08:44:40.690 plugin-container[1646:7877] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:44:41 INFO - PID 1645 | 2017-06-15 08:44:41.446 plugin-container[1647:7923] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x912b, name = 'com.apple.tsm.portname' 08:44:41 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:41 INFO - PID 1645 | 2017-06-15 08:44:41.447 plugin-container[1647:7923] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a03, name = 'com.apple.CFPasteboardClient' 08:44:41 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:41 INFO - PID 1645 | 2017-06-15 08:44:41.447 plugin-container[1647:7923] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:44:50 INFO - PID 1645 | RSS: Main: 214638592 08:44:50 INFO - PID 1645 | 08:47:53 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 08:47:55 INFO - PID 1645 | RSS: Main: 240267264 08:47:55 INFO - PID 1645 | 08:50:57 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 08:50:57 INFO - PID 1645 | RSS: Main: 247713792 08:50:57 INFO - PID 1645 | 08:53:59 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 08:53:59 INFO - PID 1645 | RSS: Main: 250490880 08:53:59 INFO - PID 1645 | 08:57:01 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 08:57:02 INFO - PID 1645 | RSS: Main: 254951424 08:57:02 INFO - PID 1645 | 09:00:04 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 09:00:05 INFO - PID 1645 | RSS: Main: 253378560 09:00:05 INFO - PID 1645 | 09:03:07 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 09:03:07 INFO - PID 1645 | RSS: Main: 251568128 09:03:07 INFO - PID 1645 | 09:03:07 INFO - PID 1645 | __start_tp_report 09:03:07 INFO - PID 1645 | _x_x_mozilla_page_load 09:03:07 INFO - PID 1645 | _x_x_mozilla_page_load_details 09:03:07 INFO - PID 1645 | |i|pagename|runs| 09:03:07 INFO - PID 1645 | |0;dojo.html;2968.4359971605386;3308.7849289796163;3405.387030334539;3448.1552019755163;3509.1402606361444;6055.909161362578;6232.968835155829;6247.687615619212;6267.843303917404;6273.5294852886045;6280.277768056663;6291.653959032252;6308.558400911938;6309.873802523946;6317.557770956023;5462.20797984294;5469.316335458067;5482.122860342346;5506.779728810843;5583.888322233553;4515.706479078865;5468.28912241408;5508.063629183049;5513.641613295143;5545.334559852803;5336.092864213074;5338.332708411449;5355.571554275649;5378.91931621026;5379.757910894019;4398.648108151372;5417.187421886709;5436.592255580807;5437.048516509634;5464.1257398816;5301.9926214019215;5450.345958484968;5455.290812194429;5456.454354564551;5491.752871120809;6192.071189321593;6295.118683384329;6303.936960630381;6307.369263073701;6310.747570097191;6512.251091124486;6621.205455345389;6621.437177839889;6770.289119642465;6809.625470599115;6767.42476889465;6809.931900681029;6821.624810635462;6878.449724022066;6911.412529934962;6361.491080713532;6413.29453760086;6418.26189988148;6425.164728585254;6427.678616069178;7022.438204943593;7026.543274687129;7028.121484814398;7057.50597458173;7059.2940705928995;6490.4807615390655;6542.509311801538;6572.507061970276;6574.079628851964;6676.0987266718375;6719.294474080151;6768.898466523006;6818.045473633695;6837.623930683761;6843.657817109224;6709.496787740939;6711.966440167768;6770.255271920085;6775.254721980578;6791.626460544718;6782.152230971129;6785.42323902464;6792.252852186256;6806.353396427331;6847.691853866588;4957.405111386584;5274.446183150791;5381.139017757218;5422.484863937999;5442.183672449164;4661.463931647836;4746.905061898743;4760.476347601761;4850.175470170079;4865.489123641966;6182.6599537024995;6312.52656050798;6321.399467050623;6321.3994670507145;6362.236531616236;6719.832004199837;6848.212455567574;6895.34494223048;6900.171979362408;6904.965475172692;6321.241451025814;6400.775972841021;6420.775272865428;6428.421442070143;6440.6135631862235;4512.3908272382805;5961.433663801932;5981.970090149608;6022.728977196037;6048.002079656895;6389.456896163878;6951.826204344932;6990.790276291617;7000;7031.08595882532;6651.102101216467;6740.2248741393405;6759.695813688298;6776.491763117688;6863.2793556677825;7019.333163349474;7047.964760176166;7064.964675176591;7091.6099614519635;7091.680874360769;6784.898226526705;6815.318468153145;6820.897686534607;6826.112605361272;6862.828429289308;6902.068220790129;6943.583384996917;6952.061471701256;6955.965220174069;6971.163460384786;6448.5163612730175;6682.264950855305;6708.731650734111;6717.731290748316;6730.226024007106;6467.288598254282;6526.216853977553;6548.869022619426;6657.600543967377;6667.733290668319;6466.773662921775;6476.255230648348;6544.705488253136;6599.0761293418;6622.536422450382;5691.861627674564;6680.5323627345615;6690.866182676222;6692.330766923269;6739.5619284746335;6327.873442531216;6402.775902843378;6521.739130434729;6547.607143571401;6569.507286953402 09:03:07 INFO - PID 1645 | |1;ext.html;765.8162041110132;795.1015352651503;842.9325653947683;858.1247127929508;1200.2378489659063;36647.63352366476;37360.626393736056;37389.25221495572;37398.878033659;37408;37531.68639097632;37609.81195094021;37650.8117459413;37881.62118378816;38029;7668.539887606734;7771.22287771224;7773.611319434034;7793.337566306871;7806.687732490694;7756.340711039568;7920.207979202062;8139.552324622144;8156.9184308156755;8164.346852251806;8176.713815016476;8198.549079800609;8235.547044912528;8240.711575094872;8254.628541715607;8149.796255093641;8212.301954333829;8218.753437396857;8219.589020548978;8229.95885020577;8036.156203598596;8044.034715834079;8046.275835174863;8100.47346922454;8106.513609183467;35309.470357944396;35579.822100889585;35734.749283774945;35755;35825.82087089548;3511.2977404519197;3521.3133438979407;3530.452779819132;3556.270964452281;3559.608443071286;25635.615465768027;29037.274068148254;29282.414351712847;29339;29399.412011759858;3083.6453807812163;3130.9530357044664;3133.122725636825;3152.8896488622786;3167.319026409333;227039;263976;270919.6454017737;273717;320007.399963001;35463.82268088643;46061.539384606396;46266.76866615645;46269.768651156526;47218.76390618025;49016.50983490191;49325;49406;49419;49469.752651236515;11213.383263920563;14913.403463861325;15015.099094054392;15038.398464061314;15053.698926021418;12337.753244935051;12558.183718058297;12564.560240391726;12567.685807854878;12614.873851261553;13183.14309569875;13206.933965330112;13274.93362533181;13279.601611951657;13386.39761210748;5861.912071318934;5926.199963005025;5944.316403613553;5965.164876917235;5978.551608629283;960.9951950240204;971.4513266505053;977.8142152990908;990.6071704412359;1019.8776146862276;18940;22635;22934;23031.848407579553;23679.881600591885;893.6872094766779;1042.1610603464285;1053.4258828938248;1104.4753741972659;1123.9775204495863;11575.13186511015;14135.505257315945;15301.46444874424;15833.841661583467;16067.116308602905;285004;309501;343951;356604;356698;52502;52666;52686.736566316926;52899;52953;1413.6889884225454;1485.695432436348;1494.4694633405202;1497.7903093566908;1503.19579025221;28044;29755;30075.548866766578;30126.94555690628;30182.84908575443;37602.811985939894;37647.87056388313;37653.246935061696;37706.43440348342;37785;297321;298591;299293.503532481;300064.99935001245;300451;28310.735141189067;28682.13953581396;28698.282542935758;33789.49315760216;34055.65944340624;35025.944032872074;41008;41139.794301028305;42556.78721606372;43425;28128.71871281261;28438.43123137567;28490.715092849634;29054.564181537717;30017.849910750305;23278.65082023805;28749.28126796847;29728.108156754468;29832.403351933273;30197.69802301949;34200.97397078091;34511.4823277646;35068.64931350654;35698.643013569534;35706.64293357033;28738.13785586436;28963.710362896945;29788.10635680933;30010;30572.84713576418;22740.886295568416;23651.881740591187;23758.643620345363;23830.8084595773;23918 09:03:07 INFO - PID 1645 | |2;jquery.html;513.82016294297;516.7467940709053;517.9637425380223;519.9376074871016;540.6701911833783;20793.16827326909;21788.45528861775;22235.888820555894;22392.88803555982;23159.18942837001;24648;24724.75275247247;25104.12135575255;25115.497690046188;25167.370815729617;6297;6410.775622853201;6425.293217746044;6431.8392040198905;6443.387878151591;6400.583962042476;6440.388163124519;6450.032495125745;6460.41856232939;6467.838304042413;5254.658447200939;5860.501857342131;5928.6739229342365;6238.59449135807;6250.9062364064575;6276.372362763733;6298.4646305064125;6325.557210995232;6329.905051424233;6331.77838775643;5789.768409263666;5795.420457954213;5800.767969281266;5803.332616749086;5826.621269617462;24290.878545607335;27344.45311093767;30208.8489557553;30449.543256851164;30553;2253.853499522526;2285.360099172218;2306.0084163809556;2320.3503019154664;2323.1288266899915;19208.711869321833;19705.80294197054;19724.30964916221;19764.604707905906;19772.604547908963;2080.3134965461363;2135.4874830040835;2229.6989906362596;2247.5841969235744;2270.3642979965634;212739;213380;217242;217334;220656;15369.464038433756;15572.766408503881;15781.526554203392;16053.117078560792;16120.919395402949;20920.47698807542;21091.156353745984;21130.47173820636;21192.15231390758;21219.575608488052;8961.193492585699;10464;10581.99471050249;10626;10727.78544429107;9911.306208565331;10064.194864410976;10068.44623545712;10126.848097278547;10223.539940702538;8433.325333973267;8582.399232053822;9196.90803091974;9226.261899048195;9251.67619133327;2585.922422327333;2598.233521111277;2601.596752503365;2603.388203772112;2625.973740262573;809.9919000809916;908.255230710824;915.9358844880927;983.492095157346;994.7413672445072;20902.790972090388;21112;21684.566308673737;22194.89025548716;22494.55010899773;952.319091849331;964.6430820596283;1001.3090967232587;1002.2433063037361;1010.9797804043878;9477.91004034531;10923.836142457869;11076;11123.221374503868;11138.49876755548;546122;546339.2683036639;556455;556491;559527;152272.47727522804;156728.43271567367;156920.21539892227;162371.1881440609;163767;1138.7494751154898;1150.5455345138623;1165;1167.9065674746;1170.3153655111685;19064.714029289848;19339;19859.702104468157;19860.40418787439;20111.994400279636;70121;71099;71396.64301678458;72623.63688181526;73704.26295737189;87957.56021219853;92463;95884;96872;97385;12091.214071085351;12101;12295.200811947196;12341.382930853242;12386.071044671866;13848;14527.27363631793;16963.067031313396;18872.24511019599;18891.43325700231;15106;15178.772318415453;15197.468088616846;15220.619484512976;15511.45709900148;13406.262655554037;13431.05982581249;13982.300884955916;14380.35288412003;14582.562523124323;14570.927145364205;15590.76613850816;15680.372785088468;15871.920640396724;15918.28367723432;11915.702107447383;12050.578229762266;12089.15375923677;12093;12168.330741808932;15129.697406051597;15156.545303640909;15166.317515711598;15259.237038147827;15444.768328474856 09:03:07 INFO - PID 1645 | |3;mootools.html;1403.9859601403987;1466.113001634012;1479.2825479642372;1488.0773920169504;1496.266829253666;13585.728285434298;13982.580522584327;14047.43810247589;14124.364403601838;14150.221737804417;13107.213567185952;13175.209487430737;13307.534236301732;14139.293035348217;14139.787903181435;2807.5507918733006;2863.942721145576;2866.240446281737;2866.9856650716715;2869.1822830493315;2765.0598796409217;2797;2814.3808362160294;2815.6902740698506;2821.1677555121287;2777.7083406242355;2807.6350074490288;2815.5072862249126;2827.5899994500787;2828.3353411948174;2743.629610002644;2756.641636587251;2760.654918135233;2769.418422131355;2770.2381844992588;2714.823536470123;2717.728227177286;2743.396452780385;2746.9725302747115;2747.3818390862098;11512.76974460506;13465;13741.519046833413;13745.656358591115;13813.447462101503;7581.203973582805;7784.221577842227;7818.569978651172;7840.607969601497;7873.015873015873;4644.883877903046;4865.635077369211;4967;4970.428400733926;5003.924941125886;1429.2210745143818;1436.2675035731747;1443.0836418873985;1463.0782606957655;1463.5316698656434;17458.738118928228;20838.166473341047;21088.683669744965;21116.683249751266;21153.47116322089;6491.221053473613;6656.5340426169705;6707.6981535831;6764;6816.250212476623;14346.928265358814;14354.641133971525;14356.856431435552;14367.928160359132;14386.208758518173;7541.509801862861;7579.204183560757;7612.276833700789;7661.961690191513;7663.501872378278;5949.553783466171;6510.18622672166;7087.822804429828;7090.220075791659;7102.218755936843;4418.955810441855;4978.253262010728;5251.2123181523075;5388.5419739321815;5403.918941215885;1513.4854149589194;1554.556951268883;1555.416718730476;1560.84391560843;1584.6672198838373;747.4655621230846;752.8268498245434;811.8782182672646;843.9156084391634;866.259348257231;7144.714211431588;7273.8545229095125;7444.106707195171;7500.662470188855;7564.394848412114;686.5057158845623;697.4664381747967;710.5630037526884;712.3695529456469;724.051492544768;4153.1486045360625;5187;5305.389880163753;5430.728463576806;5750.769969201185;18611;18723.438296851116;18826.529336766416;18910.905445472683;19193.232270709017;18100.728489072673;18977.051147442573;19060.04699765006;19092.904535477235;19130.617387652448;9609.60660704194;9610.759731006641;9724.75688107803;9731.367461115005;9940.950295248622;7852.214778522331;7950.443468957117;8005.39959503028;8094.51432914027;8261.215184557457;12827.807582886451;17227.65544689074;17465.56336091608;17469.563260918578;17831.59273498113;16656.916715416344;16879.7468037982;17523.82476175222;18016.549586260448;18115.54711132232;10582.4708764563;10988.395638239654;11015.173861960546;11040.171987100839;11173.608923687632;12739.490420383081;12870.034747394171;12878.35608219604;12883.742325153256;12903.806442903551;9781.755456113655;9949.253805964438;9957.452340121074;10115.696529104138;10487.580496780349;9752.853707194256;9773.951130244302;9822.950885245527;9959.352642078242;9981.650642227487;9363.625454981997;9375.968643449352;9439.19766819828;9444.85832712496;9446.480443575669;6523.24982627004;6760.729570817112;6853.143357080365;7026.859462810613;7195.8560828784175;6825.35159159879;6976.511644184859;7104.644767761488;7225.6387180641805;7304.488685791944 09:03:07 INFO - PID 1645 | |4;prototype.html;429.8710386883938;442.54417949512015;452.36668663870574;463.2402859310731;467.3363823370813;5562.471565201305;6462.127612772274;6468.450181734553;6488;6515.250746164181;5915.87598356312;6063.363346848583;6280.497560195185;6283.497320214383;6307.864584374811;4129.669626429886;4702.224133018048;4726.125666751662;4732;4732.597729193023;4716.410448693913;4727.8653123250515;4732.455767586737;4738.455077666061;4759.833405830796;3857.40210267409;4462.995825939154;4683.672142949995;4714.622830173578;4717.669763116583;3636.690881275095;4645.88385290367;4674.275487299474;4683.46140193877;4710;3627.6009638939695;3763.755355901885;3865.342891708416;4087.6729861611;4091.57038510958;5303.893922121536;6123.020316749299;6151.477124444383;6156.84607884802;6202.472789812871;5598.964191624563;5606.747696353674;5614.719264036782;5615.241942337773;5653.604247702662;5602.299712535933;5893.499052580536;6803.4897382696;6857.040014397989;6878.8624227515165;4408.911821763579;4494.1910456117885;4706.129366067288;4706.987997580502;4717.268823332389;6967.651617419159;7134.108236470442;7160.391366733782;7174.246704096099;7193.748218812369;4260.020195355086;4774.212254977959;4789.9105077225695;4796.2805579163405;4805.399325084364;4651.209294419957;4890.486498917634;4891.951080489221;4909.386326709161;4926.8075706311065;4466.486354069324;5433.646812957145;5576.637518561281;5587.916181257285;5627.986962346817;6141.570090093651;6328.019157030575;6782.966085169543;6810.659467026629;6840.247572766992;1326.3036905624624;1412.1103704665995;1541.6223025358634;1676.9077700726334;1683.6548507555922;1005.7787286796893;1069.946502674863;1122.9382383968796;1129.265977114882;1136.8465257190173;5529.972350138223;6149.357235287419;6270.498360131179;6282.811515654537;6287.654179020106;6201.348858369896;6882.726695561337;6889.517733758689;7001.789946301619;7036.19083805369;5369.838904832861;5662.971685141548;6025.487833534199;6289.654069026247;6309.085182648582;3181.5068664357063;3615.620359862229;3648.1609229877276;3654.9817250914107;3656.561212654445;5960.970195148997;7105.467089968273;7183;7189.604571748604;7190.101237345332;5945.316288626776;6560.620291361038;7177.712891484282;7188.4968052235845;7195;7117.75087871922;7154.391876690435;7162.4270058395205;7185.461090418135;7196.748113815991;5899.613590806243;6061.636301821904;6599.340065993554;6674.632895190811;6990.300969903173;5787.5369970401425;6856.760013399507;6858.074159988338;6870.7595234166565;6905.51531420747;5881.911771323348;5952.613080149949;7074.540154889915;7150.7497237596435;7159.997600336062;4017.2969730297664;4750.88122796919;4781.95218047829;4790.928136077892;4850.223964165716;5276.788928442819;6237.875242495215;6663.800085997428;7040.753573624898;7040.894386584103;5711.7429715662065;6548.279689234276;6617.735290588515;6741.595504269563;6746.9325306746305;6649.368310010541;6710.429613482908;6765.763198288037;6773.627450490073;6805.93194068053;5455.5908306876345;6322.146510221061;6621.702023408862;6622.37087476689;6664.866702665822;5707.828765137053;6377.4579160771855;6642.933570664231;6733.764318248838;6771.424428923399;5915;6566.540342176002;6583.275839657538;6592.934070659232;6610.305917878746 09:03:07 INFO - PID 1645 | |5;yui.html;739.5414842797466;798.3812545277412;817.6075483767787;844.8310337932415;846.8856704344913;16551.917240413797;16684.998900065988;16901.323947042103;16960.575985600368;16977.74533381999;14995.100293982368;17795.466136015908;18225.453236402896;18278.451646450594;18302.450926472193;841.6969890839293;950.7575568230092;960.3613596958032;981.1071924548662;981.6760469045233;973.459729849933;980.6518685866522;983.9422620682794;985.6895078050427;985.9802803943917;987.5654711926766;1007.6372505897907;1021.0147207944332;1030.3560274828233;1032.3444612670933;1032.1175395037249;1033.9534720937577;1040.7970445763117;1041.3647674918293;1046.8953104689547;764.8661484240291;765.4106338119617;766.1342682768492;766.3141488367879;769.9076110866675;15312;16275;17482.388116415863;17580.472585822317;17629.03040332781;17615.29538818446;17774.022428766413;17982.37061702847;18007.549811254823;18180.818191818045;14884.702305953928;14902.403903843833;14943.178125203109;15022.399104035934;15048.774268385983;3569.5002699622073;3591.425371940477;3593.568771747381;3615.7468977171607;3622.8913132606062;125764;142590.28704856514;153995.23002384917;158531;161100;10765.892341076646;10850.023497885228;10905.400202988752;10905.509252083675;11013.724656883482;22166.335009949726;22171.667424988314;22231.221907233168;22360.55278894413;22419.439514011952;7888.64501097452;8018;8262.628181731836;8310.044344900292;8351.749447516584;4075.3275709508193;4124.1133156371525;4152.688548358885;4154.709170358106;4156.896077598084;4625.699329543636;4701.247800351927;4732.73969931657;4742.122707299091;4746.738929358849;971.8056388722284;987.1461186074138;1019.230480986865;1037.7042542875395;1041.2450973044633;570.7888081409822;601.3835818286292;658.5949640970769;676.0569006236273;687.5049964025893;12824.294663793395;13974;14081.436742530184;14139;14379.856201437851;634.660456655687;648.614074625597;663.2041550139852;666.0974379715416;666.8932970724661;5124.385073791094;5545.3622833374675;5736.139579063174;5790;5875.706214689249;124844;130613;133765.33117334545;133979;135237;108800.4559977195;109198.54007299604;113476;117208;119368;90578.73553161092;98384.50807746059;102842.97157028333;116312.41843790727;122174;12169.574064907685;12265.754684906431;12813.487460501476;13035.543755968874;13355.732885342433;46468.53531464642;47344;48217.51782482271;48473.27290090581;49298.75350623224;61923;63545.36454635579;64883.67558162368;66448.33551664422;68652.656736716;16503.587410314838;16516.83483165153;16713.2479038446;16752.832471675127;16923.238454269835;18538.711054622563;19195.424137275902;19420.902895485433;19514.512137196685;19524.21903123859;17563.56091097733;17870.017149056926;18211.18049687741;18301.54246143857;18331.908340458747;15176.544703658908;15324.467553244587;17166.914165429593;17779.555511112325;18136.18387172607;16838.410655626994;17590.73613895767;17889.37387191442;18005.459836204936;18138;11323.660290191307;11961.342126183143;12516.186447880858;12613.621591352274;12798.808017879925;13744.175349479063;14404.711905762035;14858.88558358149;14888;14934.178620175995 09:03:07 INFO - PID 1645 | __end_tp_report 09:03:07 INFO - PID 1645 | __start_cc_report 09:03:07 INFO - PID 1645 | _x_x_mozilla_cycle_collect,408 09:03:07 INFO - PID 1645 | __end_cc_report 09:03:07 INFO - PID 1645 | __startTimestamp1497542587548__endTimestamp 09:03:07 INFO - PID 1645 | 09:03:07 INFO - PID 1645 | ------- Summary: start ------- 09:03:07 INFO - PID 1645 | Number of tests: 6 09:03:07 INFO - PID 1645 | 09:03:07 INFO - PID 1645 | [#0] dojo.html Cycles:165 Average:6223.46 Median:6476.26 stddev:819.35 (12.7%) stddev-sans-first:781.06 09:03:07 INFO - PID 1645 | Values: 2968.4 3308.8 3405.4 3448.2 3509.1 6055.9 6233.0 6247.7 6267.8 6273.5 6280.3 6291.7 6308.6 6309.9 6317.6 5462.2 5469.3 5482.1 5506.8 5583.9 4515.7 5468.3 5508.1 5513.6 5545.3 5336.1 5338.3 5355.6 5378.9 5379.8 4398.6 5417.2 5436.6 5437.0 5464.1 5302.0 5450.3 5455.3 5456.5 5491.8 6192.1 6295.1 6303.9 6307.4 6310.7 6512.3 6621.2 6621.4 6770.3 6809.6 6767.4 6809.9 6821.6 6878.4 6911.4 6361.5 6413.3 6418.3 6425.2 6427.7 7022.4 7026.5 7028.1 7057.5 7059.3 6490.5 6542.5 6572.5 6574.1 6676.1 6719.3 6768.9 6818.0 6837.6 6843.7 6709.5 6712.0 6770.3 6775.3 6791.6 6782.2 6785.4 6792.3 6806.4 6847.7 4957.4 5274.4 5381.1 5422.5 5442.2 4661.5 4746.9 4760.5 4850.2 4865.5 6182.7 6312.5 6321.4 6321.4 6362.2 6719.8 6848.2 6895.3 6900.2 6905.0 6321.2 6400.8 6420.8 6428.4 6440.6 4512.4 5961.4 5982.0 6022.7 6048.0 6389.5 6951.8 6990.8 7000.0 7031.1 6651.1 6740.2 6759.7 6776.5 6863.3 7019.3 7048.0 7065.0 7091.6 7091.7 6784.9 6815.3 6820.9 6826.1 6862.8 6902.1 6943.6 6952.1 6956.0 6971.2 6448.5 6682.3 6708.7 6717.7 6730.2 6467.3 6526.2 6548.9 6657.6 6667.7 6466.8 6476.3 6544.7 6599.1 6622.5 5691.9 6680.5 6690.9 6692.3 6739.6 6327.9 6402.8 6521.7 6547.6 6569.5 09:03:07 INFO - PID 1645 | 09:03:07 INFO - PID 1645 | [#1] ext.html Cycles:180 Average:44564.98 Median:34768.71 stddev:79324.62 (228.1%) stddev-sans-first:79478.98 09:03:07 INFO - PID 1645 | Values: 765.8 795.1 842.9 858.1 1200.2 36647.6 37360.6 37389.3 37398.9 37408.0 37531.7 37609.8 37650.8 37881.6 38029.0 7668.5 7771.2 7773.6 7793.3 7806.7 7756.3 7920.2 8139.6 8156.9 8164.3 8176.7 8198.5 8235.5 8240.7 8254.6 8149.8 8212.3 8218.8 8219.6 8230.0 8036.2 8044.0 8046.3 8100.5 8106.5 35309.5 35579.8 35734.7 35755.0 35825.8 3511.3 3521.3 3530.5 3556.3 3559.6 25635.6 29037.3 29282.4 29339.0 29399.4 3083.6 3131.0 3133.1 3152.9 3167.3 227039.0 263976.0 270919.6 273717.0 320007.4 35463.8 46061.5 46266.8 46269.8 47218.8 49016.5 49325.0 49406.0 49419.0 49469.8 11213.4 14913.4 15015.1 15038.4 15053.7 12337.8 12558.2 12564.6 12567.7 12614.9 13183.1 13206.9 13274.9 13279.6 13386.4 5861.9 5926.2 5944.3 5965.2 5978.6 961.0 971.5 977.8 990.6 1019.9 18940.0 22635.0 22934.0 23031.8 23679.9 893.7 1042.2 1053.4 1104.5 1124.0 11575.1 14135.5 15301.5 15833.8 16067.1 285004.0 309501.0 343951.0 356604.0 356698.0 52502.0 52666.0 52686.7 52899.0 52953.0 1413.7 1485.7 1494.5 1497.8 1503.2 28044.0 29755.0 30075.5 30126.9 30182.8 37602.8 37647.9 37653.2 37706.4 37785.0 297321.0 298591.0 299293.5 300065.0 300451.0 28310.7 28682.1 28698.3 33789.5 34055.7 35025.9 41008.0 41139.8 42556.8 43425.0 28128.7 28438.4 28490.7 29054.6 30017.8 23278.7 28749.3 29728.1 29832.4 30197.7 34201.0 34511.5 35068.6 35698.6 35706.6 28738.1 28963.7 29788.1 30010.0 30572.8 22740.9 23651.9 23758.6 23830.8 23918.0 09:03:07 INFO - PID 1645 | 09:03:07 INFO - PID 1645 | [#2] jquery.html Cycles:180 Average:40263.53 Median:121222.23 stddev:97359.50 (80.3%) stddev-sans-first:97586.87 09:03:07 INFO - PID 1645 | Values: 513.8 516.7 518.0 519.9 540.7 20793.2 21788.5 22235.9 22392.9 23159.2 24648.0 24724.8 25104.1 25115.5 25167.4 6297.0 6410.8 6425.3 6431.8 6443.4 6400.6 6440.4 6450.0 6460.4 6467.8 5254.7 5860.5 5928.7 6238.6 6250.9 6276.4 6298.5 6325.6 6329.9 6331.8 5789.8 5795.4 5800.8 5803.3 5826.6 24290.9 27344.5 30208.8 30449.5 30553.0 2253.9 2285.4 2306.0 2320.4 2323.1 19208.7 19705.8 19724.3 19764.6 19772.6 2080.3 2135.5 2229.7 2247.6 2270.4 212739.0 213380.0 217242.0 217334.0 220656.0 15369.5 15572.8 15781.5 16053.1 16120.9 20920.5 21091.2 21130.5 21192.2 21219.6 8961.2 10464.0 10582.0 10626.0 10727.8 9911.3 10064.2 10068.4 10126.8 10223.5 8433.3 8582.4 9196.9 9226.3 9251.7 2585.9 2598.2 2601.6 2603.4 2626.0 810.0 908.3 915.9 983.5 994.7 20902.8 21112.0 21684.6 22194.9 22494.6 952.3 964.6 1001.3 1002.2 1011.0 9477.9 10923.8 11076.0 11123.2 11138.5 546122.0 546339.3 556455.0 556491.0 559527.0 152272.5 156728.4 156920.2 162371.2 163767.0 1138.7 1150.5 1165.0 1167.9 1170.3 19064.7 19339.0 19859.7 19860.4 20112.0 70121.0 71099.0 71396.6 72623.6 73704.3 87957.6 92463.0 95884.0 96872.0 97385.0 12091.2 12101.0 12295.2 12341.4 12386.1 13848.0 14527.3 16963.1 18872.2 18891.4 15106.0 15178.8 15197.5 15220.6 15511.5 13406.3 13431.1 13982.3 14380.4 14582.6 14570.9 15590.8 15680.4 15871.9 15918.3 11915.7 12050.6 12089.2 12093.0 12168.3 15129.7 15156.5 15166.3 15259.2 15444.8 09:03:07 INFO - PID 1645 | 09:03:07 INFO - PID 1645 | [#3] mootools.html Cycles:180 Average:8427.43 Median:2824.38 stddev:5622.74 (199.1%) stddev-sans-first:5613.74 09:03:07 INFO - PID 1645 | Values: 1404.0 1466.1 1479.3 1488.1 1496.3 13585.7 13982.6 14047.4 14124.4 14150.2 13107.2 13175.2 13307.5 14139.3 14139.8 2807.6 2863.9 2866.2 2867.0 2869.2 2765.1 2797.0 2814.4 2815.7 2821.2 2777.7 2807.6 2815.5 2827.6 2828.3 2743.6 2756.6 2760.7 2769.4 2770.2 2714.8 2717.7 2743.4 2747.0 2747.4 11512.8 13465.0 13741.5 13745.7 13813.4 7581.2 7784.2 7818.6 7840.6 7873.0 4644.9 4865.6 4967.0 4970.4 5003.9 1429.2 1436.3 1443.1 1463.1 1463.5 17458.7 20838.2 21088.7 21116.7 21153.5 6491.2 6656.5 6707.7 6764.0 6816.3 14346.9 14354.6 14356.9 14367.9 14386.2 7541.5 7579.2 7612.3 7662.0 7663.5 5949.6 6510.2 7087.8 7090.2 7102.2 4419.0 4978.3 5251.2 5388.5 5403.9 1513.5 1554.6 1555.4 1560.8 1584.7 747.5 752.8 811.9 843.9 866.3 7144.7 7273.9 7444.1 7500.7 7564.4 686.5 697.5 710.6 712.4 724.1 4153.1 5187.0 5305.4 5430.7 5750.8 18611.0 18723.4 18826.5 18910.9 19193.2 18100.7 18977.1 19060.0 19092.9 19130.6 9609.6 9610.8 9724.8 9731.4 9941.0 7852.2 7950.4 8005.4 8094.5 8261.2 12827.8 17227.7 17465.6 17469.6 17831.6 16656.9 16879.7 17523.8 18016.5 18115.5 10582.5 10988.4 11015.2 11040.2 11173.6 12739.5 12870.0 12878.4 12883.7 12903.8 9781.8 9949.3 9957.5 10115.7 10487.6 9752.9 9774.0 9823.0 9959.4 9981.7 9363.6 9376.0 9439.2 9444.9 9446.5 6523.2 6760.7 6853.1 7026.9 7195.9 6825.4 6976.5 7104.6 7225.6 7304.5 09:03:07 INFO - PID 1645 | 09:03:07 INFO - PID 1645 | [#4] prototype.html Cycles:180 Average:5430.78 Median:5930.60 stddev:1681.36 (28.4%) stddev-sans-first:1643.65 09:03:07 INFO - PID 1645 | Values: 429.9 442.5 452.4 463.2 467.3 5562.5 6462.1 6468.5 6488.0 6515.3 5915.9 6063.4 6280.5 6283.5 6307.9 4129.7 4702.2 4726.1 4732.0 4732.6 4716.4 4727.9 4732.5 4738.5 4759.8 3857.4 4463.0 4683.7 4714.6 4717.7 3636.7 4645.9 4674.3 4683.5 4710.0 3627.6 3763.8 3865.3 4087.7 4091.6 5303.9 6123.0 6151.5 6156.8 6202.5 5599.0 5606.7 5614.7 5615.2 5653.6 5602.3 5893.5 6803.5 6857.0 6878.9 4408.9 4494.2 4706.1 4707.0 4717.3 6967.7 7134.1 7160.4 7174.2 7193.7 4260.0 4774.2 4789.9 4796.3 4805.4 4651.2 4890.5 4892.0 4909.4 4926.8 4466.5 5433.6 5576.6 5587.9 5628.0 6141.6 6328.0 6783.0 6810.7 6840.2 1326.3 1412.1 1541.6 1676.9 1683.7 1005.8 1069.9 1122.9 1129.3 1136.8 5530.0 6149.4 6270.5 6282.8 6287.7 6201.3 6882.7 6889.5 7001.8 7036.2 5369.8 5663.0 6025.5 6289.7 6309.1 3181.5 3615.6 3648.2 3655.0 3656.6 5961.0 7105.5 7183.0 7189.6 7190.1 5945.3 6560.6 7177.7 7188.5 7195.0 7117.8 7154.4 7162.4 7185.5 7196.7 5899.6 6061.6 6599.3 6674.6 6990.3 5787.5 6856.8 6858.1 6870.8 6905.5 5881.9 5952.6 7074.5 7150.7 7160.0 4017.3 4750.9 4782.0 4790.9 4850.2 5276.8 6237.9 6663.8 7040.8 7040.9 5711.7 6548.3 6617.7 6741.6 6746.9 6649.4 6710.4 6765.8 6773.6 6805.9 5455.6 6322.1 6621.7 6622.4 6664.9 5707.8 6377.5 6642.9 6733.8 6771.4 5915.0 6566.5 6583.3 6592.9 6610.3 09:03:07 INFO - PID 1645 | 09:03:07 INFO - PID 1645 | [#5] yui.html Cycles:180 Average:25164.16 Median:18071.87 stddev:38141.20 (211.1%) stddev-sans-first:38204.11 09:03:07 INFO - PID 1645 | Values: 739.5 798.4 817.6 844.8 846.9 16551.9 16685.0 16901.3 16960.6 16977.7 14995.1 17795.5 18225.5 18278.5 18302.5 841.7 950.8 960.4 981.1 981.7 973.5 980.7 983.9 985.7 986.0 987.6 1007.6 1021.0 1030.4 1032.3 1032.1 1034.0 1040.8 1041.4 1046.9 764.9 765.4 766.1 766.3 769.9 15312.0 16275.0 17482.4 17580.5 17629.0 17615.3 17774.0 17982.4 18007.5 18180.8 14884.7 14902.4 14943.2 15022.4 15048.8 3569.5 3591.4 3593.6 3615.7 3622.9 125764.0 142590.3 153995.2 158531.0 161100.0 10765.9 10850.0 10905.4 10905.5 11013.7 22166.3 22171.7 22231.2 22360.6 22419.4 7888.6 8018.0 8262.6 8310.0 8351.7 4075.3 4124.1 4152.7 4154.7 4156.9 4625.7 4701.2 4732.7 4742.1 4746.7 971.8 987.1 1019.2 1037.7 1041.2 570.8 601.4 658.6 676.1 687.5 12824.3 13974.0 14081.4 14139.0 14379.9 634.7 648.6 663.2 666.1 666.9 5124.4 5545.4 5736.1 5790.0 5875.7 124844.0 130613.0 133765.3 133979.0 135237.0 108800.5 109198.5 113476.0 117208.0 119368.0 90578.7 98384.5 102843.0 116312.4 122174.0 12169.6 12265.8 12813.5 13035.5 13355.7 46468.5 47344.0 48217.5 48473.3 49298.8 61923.0 63545.4 64883.7 66448.3 68652.7 16503.6 16516.8 16713.2 16752.8 16923.2 18538.7 19195.4 19420.9 19514.5 19524.2 17563.6 17870.0 18211.2 18301.5 18331.9 15176.5 15324.5 17166.9 17779.6 18136.2 16838.4 17590.7 17889.4 18005.5 18138.0 11323.7 11961.3 12516.2 12613.6 12798.8 13744.2 14404.7 14858.9 14888.0 14934.2 09:03:07 INFO - PID 1645 | -------- Summary: end -------- 09:03:07 INFO - PID 1645 | 09:03:08 INFO - TEST-INFO | 1645: exit 0 09:03:08 INFO - TEST-OK | dromaeo_css | took 1142399ms 09:03:08 INFO - TEST-START | kraken 09:03:08 INFO - operating with platform_type : mac_ 09:03:08 INFO - Initialising browser for kraken test... 09:03:08 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmpzq6UO3/profile 09:03:08 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 09:03:41 INFO - TEST-INFO | 1656: killed by SIGTERM 09:03:41 INFO - Browser initialized. 09:03:41 INFO - Running cycle 1/1 for kraken test... 09:03:41 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmpzq6UO3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:43 INFO - PID 1660 | 2017-06-15 09:03:43.126 plugin-container[1661:10564] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 09:03:43 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:43 INFO - PID 1660 | 2017-06-15 09:03:43.126 plugin-container[1661:10564] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 09:03:43 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:43 INFO - PID 1660 | 2017-06-15 09:03:43.126 plugin-container[1661:10564] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:03:43 INFO - PID 1660 | 2017-06-15 09:03:43.889 plugin-container[1662:10610] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 09:03:43 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:43 INFO - PID 1660 | 2017-06-15 09:03:43.889 plugin-container[1662:10610] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 09:03:43 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:43 INFO - PID 1660 | 2017-06-15 09:03:43.889 plugin-container[1662:10610] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:03:53 INFO - PID 1660 | RSS: Main: 214732800 09:03:53 INFO - PID 1660 | 09:03:56 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 09:03:57 INFO - PID 1660 | RSS: Main: 228126720 09:03:57 INFO - PID 1660 | 09:03:59 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 09:04:00 INFO - PID 1660 | RSS: Main: 238006272 09:04:00 INFO - PID 1660 | 09:04:03 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 09:04:04 INFO - PID 1660 | RSS: Main: 240627712 09:04:04 INFO - PID 1660 | 09:04:06 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 09:04:07 INFO - PID 1660 | RSS: Main: 242597888 09:04:07 INFO - PID 1660 | 09:04:09 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:04:09 INFO - PID 1660 | RSS: Main: 244629504 09:04:09 INFO - PID 1660 | 09:04:12 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 09:04:13 INFO - PID 1660 | RSS: Main: 249032704 09:04:13 INFO - PID 1660 | 09:04:16 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 09:04:17 INFO - PID 1660 | RSS: Main: 249622528 09:04:17 INFO - PID 1660 | 09:04:20 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 09:04:20 INFO - PID 1660 | RSS: Main: 249434112 09:04:20 INFO - PID 1660 | 09:04:22 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:04:22 INFO - PID 1660 | RSS: Main: 249176064 09:04:22 INFO - PID 1660 | 09:04:24 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:04:25 INFO - PID 1660 | RSS: Main: 248659968 09:04:25 INFO - PID 1660 | 09:04:27 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:04:28 INFO - PID 1660 | RSS: Main: 248590336 09:04:28 INFO - PID 1660 | 09:04:31 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:04:31 INFO - PID 1660 | RSS: Main: 249311232 09:04:31 INFO - PID 1660 | 09:04:34 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:04:35 INFO - PID 1660 | RSS: Main: 249298944 09:04:35 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 09:04:37 INFO - PID 1660 | RSS: Main: 250494976 09:04:37 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | __start_tp_report 09:04:37 INFO - PID 1660 | _x_x_mozilla_page_load 09:04:37 INFO - PID 1660 | _x_x_mozilla_page_load_details 09:04:37 INFO - PID 1660 | |i|pagename|runs| 09:04:37 INFO - PID 1660 | |0;ai-astar;104;125;152;152;116;115;115;128;166;148 09:04:37 INFO - PID 1660 | |1;audio-beat-detection;138;121;153;165;114;148;158;97;173;147 09:04:37 INFO - PID 1660 | |2;audio-dft;199;224;248;285;198;222;199;207;210;267 09:04:37 INFO - PID 1660 | |3;audio-fft;69;82;72;140;47;80;91;84;137;67 09:04:37 INFO - PID 1660 | |4;audio-oscillator;69;68;111;74;101;113;93;95;65;107 09:04:37 INFO - PID 1660 | |5;imaging-gaussian-blur;91;92;92;91;93;93;93;142;106;127 09:04:37 INFO - PID 1660 | |6;imaging-darkroom;97;101;96;91;141;156;96;95;100;96 09:04:37 INFO - PID 1660 | |7;imaging-desaturate;92;93;92;111;147;92;90;88;92;90 09:04:37 INFO - PID 1660 | |8;json-parse-financial;50;49;50;72;51;50;53;53;52;51 09:04:37 INFO - PID 1660 | |9;json-stringify-tinderbox;55;53;59;54;61;65;79;82;82;60 09:04:37 INFO - PID 1660 | |10;stanford-crypto-aes;79;93;100;97;98;78;97;100;100;71 09:04:37 INFO - PID 1660 | |11;stanford-crypto-ccm;157;148;128;121;182;147;149;166;150;177 09:04:37 INFO - PID 1660 | |12;stanford-crypto-pbkdf2;221;228;177;180;181;180;184;181;179;181 09:04:37 INFO - PID 1660 | |13;stanford-crypto-sha256-iterative;68;67;67;64;68;66;64;67;67;63 09:04:37 INFO - PID 1660 | __end_tp_report 09:04:37 INFO - PID 1660 | __start_cc_report 09:04:37 INFO - PID 1660 | _x_x_mozilla_cycle_collect,1045 09:04:37 INFO - PID 1660 | __end_cc_report 09:04:37 INFO - PID 1660 | __startTimestamp1497542677361__endTimestamp 09:04:37 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | ------- Summary: start ------- 09:04:37 INFO - PID 1660 | Number of tests: 14 09:04:37 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | [#0] ai-astar Cycles:10 Average:132.10 Median:138.00 stddev:20.80 (15.1%) stddev-sans-first:19.42 09:04:37 INFO - PID 1660 | Values: 104.0 125.0 152.0 152.0 116.0 115.0 115.0 128.0 166.0 148.0 09:04:37 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | [#1] audio-beat-detection Cycles:10 Average:141.40 Median:155.50 stddev:24.01 (15.4%) stddev-sans-first:25.44 09:04:37 INFO - PID 1660 | Values: 138.0 121.0 153.0 165.0 114.0 148.0 158.0 97.0 173.0 147.0 09:04:37 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | [#2] audio-dft Cycles:10 Average:225.90 Median:223.00 stddev:30.78 (13.8%) stddev-sans-first:31.07 09:04:37 INFO - PID 1660 | Values: 199.0 224.0 248.0 285.0 198.0 222.0 199.0 207.0 210.0 267.0 09:04:37 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | [#3] audio-fft Cycles:10 Average:86.90 Median:76.00 stddev:29.73 (39.1%) stddev-sans-first:30.82 09:04:37 INFO - PID 1660 | Values: 69.0 82.0 72.0 140.0 47.0 80.0 91.0 84.0 137.0 67.0 09:04:37 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:68.50 stddev:18.91 (27.6%) stddev-sans-first:18.53 09:04:37 INFO - PID 1660 | Values: 69.0 68.0 111.0 74.0 101.0 113.0 93.0 95.0 65.0 107.0 09:04:37 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:102.00 Median:92.00 stddev:18.03 (19.6%) stddev-sans-first:18.68 09:04:37 INFO - PID 1660 | Values: 91.0 92.0 92.0 91.0 93.0 93.0 93.0 142.0 106.0 127.0 09:04:37 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | [#6] imaging-darkroom Cycles:10 Average:106.90 Median:95.50 stddev:22.37 (23.4%) stddev-sans-first:23.44 09:04:37 INFO - PID 1660 | Values: 97.0 101.0 96.0 91.0 141.0 156.0 96.0 95.0 100.0 96.0 09:04:37 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | [#7] imaging-desaturate Cycles:10 Average:98.70 Median:92.00 stddev:18.14 (19.7%) stddev-sans-first:19.08 09:04:37 INFO - PID 1660 | Values: 92.0 93.0 92.0 111.0 147.0 92.0 90.0 88.0 92.0 90.0 09:04:37 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | [#8] json-parse-financial Cycles:10 Average:53.10 Median:51.50 stddev:6.77 (13.2%) stddev-sans-first:7.09 09:04:37 INFO - PID 1660 | Values: 50.0 49.0 50.0 72.0 51.0 50.0 53.0 53.0 52.0 51.0 09:04:37 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:63.00 stddev:11.62 (18.5%) stddev-sans-first:11.75 09:04:37 INFO - PID 1660 | Values: 55.0 53.0 59.0 54.0 61.0 65.0 79.0 82.0 82.0 60.0 09:04:37 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | [#10] stanford-crypto-aes Cycles:10 Average:91.30 Median:86.00 stddev:10.95 (12.7%) stddev-sans-first:10.68 09:04:37 INFO - PID 1660 | Values: 79.0 93.0 100.0 97.0 98.0 78.0 97.0 100.0 100.0 71.0 09:04:37 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:152.50 Median:153.50 stddev:19.25 (12.5%) stddev-sans-first:20.35 09:04:37 INFO - PID 1660 | Values: 157.0 148.0 128.0 121.0 182.0 147.0 149.0 166.0 150.0 177.0 09:04:37 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.20 Median:181.00 stddev:18.76 (10.4%) stddev-sans-first:15.98 09:04:37 INFO - PID 1660 | Values: 221.0 228.0 177.0 180.0 181.0 180.0 184.0 181.0 179.0 181.0 09:04:37 INFO - PID 1660 | 09:04:37 INFO - PID 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:67.00 stddev:1.79 (2.7%) stddev-sans-first:1.76 09:04:37 INFO - PID 1660 | Values: 68.0 67.0 67.0 64.0 68.0 66.0 64.0 67.0 67.0 63.0 09:04:37 INFO - PID 1660 | -------- Summary: end -------- 09:04:37 INFO - PID 1660 | 09:04:38 INFO - TEST-INFO | 1660: exit 0 09:04:38 INFO - TEST-OK | kraken | took 90056ms 09:04:38 INFO - SUITE-END | took 1232s 09:04:38 INFO - Completed test suite (00:20:32) 09:04:38 INFO - javascript benchmark 09:04:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8339.141592656362, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2968.4359971605386, 3308.7849289796163, 3405.387030334539, 3448.1552019755163, 3509.1402606361444, 6055.909161362578, 6232.968835155829, 6247.687615619212, 6267.843303917404, 6273.5294852886045, 6280.277768056663, 6291.653959032252, 6308.558400911938, 6309.873802523946, 6317.557770956023, 5462.20797984294, 5469.316335458067, 5482.122860342346, 5506.779728810843, 5583.888322233553, 4515.706479078865, 5468.28912241408, 5508.063629183049, 5513.641613295143, 5545.334559852803, 5336.092864213074, 5338.332708411449, 5355.571554275649, 5378.91931621026, 5379.757910894019, 4398.648108151372, 5417.187421886709, 5436.592255580807, 5437.048516509634, 5464.1257398816, 5301.9926214019215, 5450.345958484968, 5455.290812194429, 5456.454354564551, 5491.752871120809, 6192.071189321593, 6295.118683384329, 6303.936960630381, 6307.369263073701, 6310.747570097191, 6512.251091124486, 6621.205455345389, 6621.437177839889, 6770.289119642465, 6809.625470599115, 6767.42476889465, 6809.931900681029, 6821.624810635462, 6878.449724022066, 6911.412529934962, 6361.491080713532, 6413.29453760086, 6418.26189988148, 6425.164728585254, 6427.678616069178, 7022.438204943593, 7026.543274687129, 7028.121484814398, 7057.50597458173, 7059.2940705928995, 6490.4807615390655, 6542.509311801538, 6572.507061970276, 6574.079628851964, 6676.0987266718375, 6719.294474080151, 6768.898466523006, 6818.045473633695, 6837.623930683761, 6843.657817109224, 6709.496787740939, 6711.966440167768, 6770.255271920085, 6775.254721980578, 6791.626460544718, 6782.152230971129, 6785.42323902464, 6792.252852186256, 6806.353396427331, 6847.691853866588, 4957.405111386584, 5274.446183150791, 5381.139017757218, 5422.484863937999, 5442.183672449164, 4661.463931647836, 4746.905061898743, 4760.476347601761, 4850.175470170079, 4865.489123641966, 6182.6599537024995, 6312.52656050798, 6321.399467050623, 6321.3994670507145, 6362.236531616236, 6719.832004199837, 6848.212455567574, 6895.34494223048, 6900.171979362408, 6904.965475172692, 6321.241451025814, 6400.775972841021, 6420.775272865428, 6428.421442070143, 6440.6135631862235, 4512.3908272382805, 5961.433663801932, 5981.970090149608, 6022.728977196037, 6048.002079656895, 6389.456896163878, 6951.826204344932, 6990.790276291617, 7000.0, 7031.08595882532, 6651.102101216467, 6740.2248741393405, 6759.695813688298, 6776.491763117688, 6863.2793556677825, 7019.333163349474, 7047.964760176166, 7064.964675176591, 7091.6099614519635, 7091.680874360769, 6784.898226526705, 6815.318468153145, 6820.897686534607, 6826.112605361272, 6862.828429289308, 6902.068220790129, 6943.583384996917, 6952.061471701256, 6955.965220174069, 6971.163460384786, 6448.5163612730175, 6682.264950855305, 6708.731650734111, 6717.731290748316, 6730.226024007106, 6467.288598254282, 6526.216853977553, 6548.869022619426, 6657.600543967377, 6667.733290668319, 6466.773662921775, 6476.255230648348, 6544.705488253136, 6599.0761293418, 6622.536422450382, 5691.861627674564, 6680.5323627345615, 6690.866182676222, 6692.330766923269, 6739.5619284746335, 6327.873442531216, 6402.775902843378, 6521.739130434729, 6547.607143571401, 6569.507286953402], "value": 6161.538058377606, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.8162041110132, 795.1015352651503, 842.9325653947683, 858.1247127929508, 1200.2378489659063, 36647.63352366476, 37360.626393736056, 37389.25221495572, 37398.878033659, 37408.0, 37531.68639097632, 37609.81195094021, 37650.8117459413, 37881.62118378816, 38029.0, 7668.539887606734, 7771.22287771224, 7773.611319434034, 7793.337566306871, 7806.687732490694, 7756.340711039568, 7920.207979202062, 8139.552324622144, 8156.9184308156755, 8164.346852251806, 8176.713815016476, 8198.549079800609, 8235.547044912528, 8240.711575094872, 8254.628541715607, 8149.796255093641, 8212.301954333829, 8218.753437396857, 8219.589020548978, 8229.95885020577, 8036.156203598596, 8044.034715834079, 8046.275835174863, 8100.47346922454, 8106.513609183467, 35309.470357944396, 35579.822100889585, 35734.749283774945, 35755.0, 35825.82087089548, 3511.2977404519197, 3521.3133438979407, 3530.452779819132, 3556.270964452281, 3559.608443071286, 25635.615465768027, 29037.274068148254, 29282.414351712847, 29339.0, 29399.412011759858, 3083.6453807812163, 3130.9530357044664, 3133.122725636825, 3152.8896488622786, 3167.319026409333, 227039.0, 263976.0, 270919.6454017737, 273717.0, 320007.399963001, 35463.82268088643, 46061.539384606396, 46266.76866615645, 46269.768651156526, 47218.76390618025, 49016.50983490191, 49325.0, 49406.0, 49419.0, 49469.752651236515, 11213.383263920563, 14913.403463861325, 15015.099094054392, 15038.398464061314, 15053.698926021418, 12337.753244935051, 12558.183718058297, 12564.560240391726, 12567.685807854878, 12614.873851261553, 13183.14309569875, 13206.933965330112, 13274.93362533181, 13279.601611951657, 13386.39761210748, 5861.912071318934, 5926.199963005025, 5944.316403613553, 5965.164876917235, 5978.551608629283, 960.9951950240204, 971.4513266505053, 977.8142152990908, 990.6071704412359, 1019.8776146862276, 18940.0, 22635.0, 22934.0, 23031.848407579553, 23679.881600591885, 893.6872094766779, 1042.1610603464285, 1053.4258828938248, 1104.4753741972659, 1123.9775204495863, 11575.13186511015, 14135.505257315945, 15301.46444874424, 15833.841661583467, 16067.116308602905, 285004.0, 309501.0, 343951.0, 356604.0, 356698.0, 52502.0, 52666.0, 52686.736566316926, 52899.0, 52953.0, 1413.6889884225454, 1485.695432436348, 1494.4694633405202, 1497.7903093566908, 1503.19579025221, 28044.0, 29755.0, 30075.548866766578, 30126.94555690628, 30182.84908575443, 37602.811985939894, 37647.87056388313, 37653.246935061696, 37706.43440348342, 37785.0, 297321.0, 298591.0, 299293.503532481, 300064.99935001245, 300451.0, 28310.735141189067, 28682.13953581396, 28698.282542935758, 33789.49315760216, 34055.65944340624, 35025.944032872074, 41008.0, 41139.794301028305, 42556.78721606372, 43425.0, 28128.71871281261, 28438.43123137567, 28490.715092849634, 29054.564181537717, 30017.849910750305, 23278.65082023805, 28749.28126796847, 29728.108156754468, 29832.403351933273, 30197.69802301949, 34200.97397078091, 34511.4823277646, 35068.64931350654, 35698.643013569534, 35706.64293357033, 28738.13785586436, 28963.710362896945, 29788.10635680933, 30010.0, 30572.84713576418, 22740.886295568416, 23651.881740591187, 23758.643620345363, 23830.8084595773, 23918.0], "value": 17175.857507764154, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.82016294297, 516.7467940709053, 517.9637425380223, 519.9376074871016, 540.6701911833783, 20793.16827326909, 21788.45528861775, 22235.888820555894, 22392.88803555982, 23159.18942837001, 24648.0, 24724.75275247247, 25104.12135575255, 25115.497690046188, 25167.370815729617, 6297.0, 6410.775622853201, 6425.293217746044, 6431.8392040198905, 6443.387878151591, 6400.583962042476, 6440.388163124519, 6450.032495125745, 6460.41856232939, 6467.838304042413, 5254.658447200939, 5860.501857342131, 5928.6739229342365, 6238.59449135807, 6250.9062364064575, 6276.372362763733, 6298.4646305064125, 6325.557210995232, 6329.905051424233, 6331.77838775643, 5789.768409263666, 5795.420457954213, 5800.767969281266, 5803.332616749086, 5826.621269617462, 24290.878545607335, 27344.45311093767, 30208.8489557553, 30449.543256851164, 30553.0, 2253.853499522526, 2285.360099172218, 2306.0084163809556, 2320.3503019154664, 2323.1288266899915, 19208.711869321833, 19705.80294197054, 19724.30964916221, 19764.604707905906, 19772.604547908963, 2080.3134965461363, 2135.4874830040835, 2229.6989906362596, 2247.5841969235744, 2270.3642979965634, 212739.0, 213380.0, 217242.0, 217334.0, 220656.0, 15369.464038433756, 15572.766408503881, 15781.526554203392, 16053.117078560792, 16120.919395402949, 20920.47698807542, 21091.156353745984, 21130.47173820636, 21192.15231390758, 21219.575608488052, 8961.193492585699, 10464.0, 10581.99471050249, 10626.0, 10727.78544429107, 9911.306208565331, 10064.194864410976, 10068.44623545712, 10126.848097278547, 10223.539940702538, 8433.325333973267, 8582.399232053822, 9196.90803091974, 9226.261899048195, 9251.67619133327, 2585.922422327333, 2598.233521111277, 2601.596752503365, 2603.388203772112, 2625.973740262573, 809.9919000809916, 908.255230710824, 915.9358844880927, 983.492095157346, 994.7413672445072, 20902.790972090388, 21112.0, 21684.566308673737, 22194.89025548716, 22494.55010899773, 952.319091849331, 964.6430820596283, 1001.3090967232587, 1002.2433063037361, 1010.9797804043878, 9477.91004034531, 10923.836142457869, 11076.0, 11123.221374503868, 11138.49876755548, 546122.0, 546339.2683036639, 556455.0, 556491.0, 559527.0, 152272.47727522804, 156728.43271567367, 156920.21539892227, 162371.1881440609, 163767.0, 1138.7494751154898, 1150.5455345138623, 1165.0, 1167.9065674746, 1170.3153655111685, 19064.714029289848, 19339.0, 19859.702104468157, 19860.40418787439, 20111.994400279636, 70121.0, 71099.0, 71396.64301678458, 72623.63688181526, 73704.26295737189, 87957.56021219853, 92463.0, 95884.0, 96872.0, 97385.0, 12091.214071085351, 12101.0, 12295.200811947196, 12341.382930853242, 12386.071044671866, 13848.0, 14527.27363631793, 16963.067031313396, 18872.24511019599, 18891.43325700231, 15106.0, 15178.772318415453, 15197.468088616846, 15220.619484512976, 15511.45709900148, 13406.262655554037, 13431.05982581249, 13982.300884955916, 14380.35288412003, 14582.562523124323, 14570.927145364205, 15590.76613850816, 15680.372785088468, 15871.920640396724, 15918.28367723432, 11915.702107447383, 12050.578229762266, 12089.15375923677, 12093.0, 12168.330741808932, 15129.697406051597, 15156.545303640909, 15166.317515711598, 15259.237038147827, 15444.768328474856], "value": 11846.613221542251, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1403.9859601403987, 1466.113001634012, 1479.2825479642372, 1488.0773920169504, 1496.266829253666, 13585.728285434298, 13982.580522584327, 14047.43810247589, 14124.364403601838, 14150.221737804417, 13107.213567185952, 13175.209487430737, 13307.534236301732, 14139.293035348217, 14139.787903181435, 2807.5507918733006, 2863.942721145576, 2866.240446281737, 2866.9856650716715, 2869.1822830493315, 2765.0598796409217, 2797.0, 2814.3808362160294, 2815.6902740698506, 2821.1677555121287, 2777.7083406242355, 2807.6350074490288, 2815.5072862249126, 2827.5899994500787, 2828.3353411948174, 2743.629610002644, 2756.641636587251, 2760.654918135233, 2769.418422131355, 2770.2381844992588, 2714.823536470123, 2717.728227177286, 2743.396452780385, 2746.9725302747115, 2747.3818390862098, 11512.76974460506, 13465.0, 13741.519046833413, 13745.656358591115, 13813.447462101503, 7581.203973582805, 7784.221577842227, 7818.569978651172, 7840.607969601497, 7873.015873015873, 4644.883877903046, 4865.635077369211, 4967.0, 4970.428400733926, 5003.924941125886, 1429.2210745143818, 1436.2675035731747, 1443.0836418873985, 1463.0782606957655, 1463.5316698656434, 17458.738118928228, 20838.166473341047, 21088.683669744965, 21116.683249751266, 21153.47116322089, 6491.221053473613, 6656.5340426169705, 6707.6981535831, 6764.0, 6816.250212476623, 14346.928265358814, 14354.641133971525, 14356.856431435552, 14367.928160359132, 14386.208758518173, 7541.509801862861, 7579.204183560757, 7612.276833700789, 7661.961690191513, 7663.501872378278, 5949.553783466171, 6510.18622672166, 7087.822804429828, 7090.220075791659, 7102.218755936843, 4418.955810441855, 4978.253262010728, 5251.2123181523075, 5388.5419739321815, 5403.918941215885, 1513.4854149589194, 1554.556951268883, 1555.416718730476, 1560.84391560843, 1584.6672198838373, 747.4655621230846, 752.8268498245434, 811.8782182672646, 843.9156084391634, 866.259348257231, 7144.714211431588, 7273.8545229095125, 7444.106707195171, 7500.662470188855, 7564.394848412114, 686.5057158845623, 697.4664381747967, 710.5630037526884, 712.3695529456469, 724.051492544768, 4153.1486045360625, 5187.0, 5305.389880163753, 5430.728463576806, 5750.769969201185, 18611.0, 18723.438296851116, 18826.529336766416, 18910.905445472683, 19193.232270709017, 18100.728489072673, 18977.051147442573, 19060.04699765006, 19092.904535477235, 19130.617387652448, 9609.60660704194, 9610.759731006641, 9724.75688107803, 9731.367461115005, 9940.950295248622, 7852.214778522331, 7950.443468957117, 8005.39959503028, 8094.51432914027, 8261.215184557457, 12827.807582886451, 17227.65544689074, 17465.56336091608, 17469.563260918578, 17831.59273498113, 16656.916715416344, 16879.7468037982, 17523.82476175222, 18016.549586260448, 18115.54711132232, 10582.4708764563, 10988.395638239654, 11015.173861960546, 11040.171987100839, 11173.608923687632, 12739.490420383081, 12870.034747394171, 12878.35608219604, 12883.742325153256, 12903.806442903551, 9781.755456113655, 9949.253805964438, 9957.452340121074, 10115.696529104138, 10487.580496780349, 9752.853707194256, 9773.951130244302, 9822.950885245527, 9959.352642078242, 9981.650642227487, 9363.625454981997, 9375.968643449352, 9439.19766819828, 9444.85832712496, 9446.480443575669, 6523.24982627004, 6760.729570817112, 6853.143357080365, 7026.859462810613, 7195.8560828784175, 6825.35159159879, 6976.511644184859, 7104.644767761488, 7225.6387180641805, 7304.488685791944], "value": 6183.830974983731, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.8710386883938, 442.54417949512015, 452.36668663870574, 463.2402859310731, 467.3363823370813, 5562.471565201305, 6462.127612772274, 6468.450181734553, 6488.0, 6515.250746164181, 5915.87598356312, 6063.363346848583, 6280.497560195185, 6283.497320214383, 6307.864584374811, 4129.669626429886, 4702.224133018048, 4726.125666751662, 4732.0, 4732.597729193023, 4716.410448693913, 4727.8653123250515, 4732.455767586737, 4738.455077666061, 4759.833405830796, 3857.40210267409, 4462.995825939154, 4683.672142949995, 4714.622830173578, 4717.669763116583, 3636.690881275095, 4645.88385290367, 4674.275487299474, 4683.46140193877, 4710.0, 3627.6009638939695, 3763.755355901885, 3865.342891708416, 4087.6729861611, 4091.57038510958, 5303.893922121536, 6123.020316749299, 6151.477124444383, 6156.84607884802, 6202.472789812871, 5598.964191624563, 5606.747696353674, 5614.719264036782, 5615.241942337773, 5653.604247702662, 5602.299712535933, 5893.499052580536, 6803.4897382696, 6857.040014397989, 6878.8624227515165, 4408.911821763579, 4494.1910456117885, 4706.129366067288, 4706.987997580502, 4717.268823332389, 6967.651617419159, 7134.108236470442, 7160.391366733782, 7174.246704096099, 7193.748218812369, 4260.020195355086, 4774.212254977959, 4789.9105077225695, 4796.2805579163405, 4805.399325084364, 4651.209294419957, 4890.486498917634, 4891.951080489221, 4909.386326709161, 4926.8075706311065, 4466.486354069324, 5433.646812957145, 5576.637518561281, 5587.916181257285, 5627.986962346817, 6141.570090093651, 6328.019157030575, 6782.966085169543, 6810.659467026629, 6840.247572766992, 1326.3036905624624, 1412.1103704665995, 1541.6223025358634, 1676.9077700726334, 1683.6548507555922, 1005.7787286796893, 1069.946502674863, 1122.9382383968796, 1129.265977114882, 1136.8465257190173, 5529.972350138223, 6149.357235287419, 6270.498360131179, 6282.811515654537, 6287.654179020106, 6201.348858369896, 6882.726695561337, 6889.517733758689, 7001.789946301619, 7036.19083805369, 5369.838904832861, 5662.971685141548, 6025.487833534199, 6289.654069026247, 6309.085182648582, 3181.5068664357063, 3615.620359862229, 3648.1609229877276, 3654.9817250914107, 3656.561212654445, 5960.970195148997, 7105.467089968273, 7183.0, 7189.604571748604, 7190.101237345332, 5945.316288626776, 6560.620291361038, 7177.712891484282, 7188.4968052235845, 7195.0, 7117.75087871922, 7154.391876690435, 7162.4270058395205, 7185.461090418135, 7196.748113815991, 5899.613590806243, 6061.636301821904, 6599.340065993554, 6674.632895190811, 6990.300969903173, 5787.5369970401425, 6856.760013399507, 6858.074159988338, 6870.7595234166565, 6905.51531420747, 5881.911771323348, 5952.613080149949, 7074.540154889915, 7150.7497237596435, 7159.997600336062, 4017.2969730297664, 4750.88122796919, 4781.95218047829, 4790.928136077892, 4850.223964165716, 5276.788928442819, 6237.875242495215, 6663.800085997428, 7040.753573624898, 7040.894386584103, 5711.7429715662065, 6548.279689234276, 6617.735290588515, 6741.595504269563, 6746.9325306746305, 6649.368310010541, 6710.429613482908, 6765.763198288037, 6773.627450490073, 6805.93194068053, 5455.5908306876345, 6322.146510221061, 6621.702023408862, 6622.37087476689, 6664.866702665822, 5707.828765137053, 6377.4579160771855, 6642.933570664231, 6733.764318248838, 6771.424428923399, 5915.0, 6566.540342176002, 6583.275839657538, 6592.934070659232, 6610.305917878746], "value": 4920.663614723948, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.5414842797466, 798.3812545277412, 817.6075483767787, 844.8310337932415, 846.8856704344913, 16551.917240413797, 16684.998900065988, 16901.323947042103, 16960.575985600368, 16977.74533381999, 14995.100293982368, 17795.466136015908, 18225.453236402896, 18278.451646450594, 18302.450926472193, 841.6969890839293, 950.7575568230092, 960.3613596958032, 981.1071924548662, 981.6760469045233, 973.459729849933, 980.6518685866522, 983.9422620682794, 985.6895078050427, 985.9802803943917, 987.5654711926766, 1007.6372505897907, 1021.0147207944332, 1030.3560274828233, 1032.3444612670933, 1032.1175395037249, 1033.9534720937577, 1040.7970445763117, 1041.3647674918293, 1046.8953104689547, 764.8661484240291, 765.4106338119617, 766.1342682768492, 766.3141488367879, 769.9076110866675, 15312.0, 16275.0, 17482.388116415863, 17580.472585822317, 17629.03040332781, 17615.29538818446, 17774.022428766413, 17982.37061702847, 18007.549811254823, 18180.818191818045, 14884.702305953928, 14902.403903843833, 14943.178125203109, 15022.399104035934, 15048.774268385983, 3569.5002699622073, 3591.425371940477, 3593.568771747381, 3615.7468977171607, 3622.8913132606062, 125764.0, 142590.28704856514, 153995.23002384917, 158531.0, 161100.0, 10765.892341076646, 10850.023497885228, 10905.400202988752, 10905.509252083675, 11013.724656883482, 22166.335009949726, 22171.667424988314, 22231.221907233168, 22360.55278894413, 22419.439514011952, 7888.64501097452, 8018.0, 8262.628181731836, 8310.044344900292, 8351.749447516584, 4075.3275709508193, 4124.1133156371525, 4152.688548358885, 4154.709170358106, 4156.896077598084, 4625.699329543636, 4701.247800351927, 4732.73969931657, 4742.122707299091, 4746.738929358849, 971.8056388722284, 987.1461186074138, 1019.230480986865, 1037.7042542875395, 1041.2450973044633, 570.7888081409822, 601.3835818286292, 658.5949640970769, 676.0569006236273, 687.5049964025893, 12824.294663793395, 13974.0, 14081.436742530184, 14139.0, 14379.856201437851, 634.660456655687, 648.614074625597, 663.2041550139852, 666.0974379715416, 666.8932970724661, 5124.385073791094, 5545.3622833374675, 5736.139579063174, 5790.0, 5875.706214689249, 124844.0, 130613.0, 133765.33117334545, 133979.0, 135237.0, 108800.4559977195, 109198.54007299604, 113476.0, 117208.0, 119368.0, 90578.73553161092, 98384.50807746059, 102842.97157028333, 116312.41843790727, 122174.0, 12169.574064907685, 12265.754684906431, 12813.487460501476, 13035.543755968874, 13355.732885342433, 46468.53531464642, 47344.0, 48217.51782482271, 48473.27290090581, 49298.75350623224, 61923.0, 63545.36454635579, 64883.67558162368, 66448.33551664422, 68652.656736716, 16503.587410314838, 16516.83483165153, 16713.2479038446, 16752.832471675127, 16923.238454269835, 18538.711054622563, 19195.424137275902, 19420.902895485433, 19514.512137196685, 19524.21903123859, 17563.56091097733, 17870.017149056926, 18211.18049687741, 18301.54246143857, 18331.908340458747, 15176.544703658908, 15324.467553244587, 17166.914165429593, 17779.555511112325, 18136.18387172607, 16838.410655626994, 17590.73613895767, 17889.37387191442, 18005.459836204936, 18138.0, 11323.660290191307, 11961.342126183143, 12516.186447880858, 12613.621591352274, 12798.808017879925, 13744.175349479063, 14404.711905762035, 14858.88558358149, 14888.0, 14934.178620175995], "value": 8814.898580020765, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1600.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 125.0, 152.0, 152.0, 116.0, 115.0, 115.0, 128.0, 166.0, 148.0], "value": 132.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 121.0, 153.0, 165.0, 114.0, 148.0, 158.0, 97.0, 173.0, 147.0], "value": 141.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 224.0, 248.0, 285.0, 198.0, 222.0, 199.0, 207.0, 210.0, 267.0], "value": 225.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 82.0, 72.0, 140.0, 47.0, 80.0, 91.0, 84.0, 137.0, 67.0], "value": 86.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 111.0, 74.0, 101.0, 113.0, 93.0, 95.0, 65.0, 107.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 92.0, 91.0, 93.0, 93.0, 93.0, 142.0, 106.0, 127.0], "value": 102.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 101.0, 96.0, 91.0, 141.0, 156.0, 96.0, 95.0, 100.0, 96.0], "value": 106.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 111.0, 147.0, 92.0, 90.0, 88.0, 92.0, 90.0], "value": 98.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 50.0, 72.0, 51.0, 50.0, 53.0, 53.0, 52.0, 51.0], "value": 53.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 59.0, 54.0, 61.0, 65.0, 79.0, 82.0, 82.0, 60.0], "value": 65.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 93.0, 100.0, 97.0, 98.0, 78.0, 97.0, 100.0, 100.0, 71.0], "value": 91.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 148.0, 128.0, 121.0, 182.0, 147.0, 149.0, 166.0, 150.0, 177.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [221.0, 228.0, 177.0, 180.0, 181.0, 180.0, 184.0, 181.0, 179.0, 181.0], "value": 189.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 64.0, 68.0, 66.0, 64.0, 67.0, 67.0, 63.0], "value": 66.1, "unit": "score"}]}]} 09:04:38 INFO - Return code: 0 09:04:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:04:38 INFO - # TBPL SUCCESS # 09:04:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:04:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:04:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:04:38 INFO - 2017-06-15 09:04:38.776 screenresolution[1668:10992] starting screenresolution argv=screenresolution get 09:04:38 INFO - 2017-06-15 09:04:38.778 screenresolution[1668:10992] Display 0: 1600x1200x32@60 09:04:38 INFO - 2017-06-15 09:04:38.784 screenresolution[1669:10996] starting screenresolution argv=screenresolution list 09:04:38 INFO - Available Modes on Display 0 09:04:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:04:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:04:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:04:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:04:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:04:39 INFO - Intel Iris: 09:04:39 INFO - Chipset Model: Intel Iris 09:04:39 INFO - Type: GPU 09:04:39 INFO - Bus: Built-In 09:04:39 INFO - VRAM (Dynamic, Max): 1536 MB 09:04:39 INFO - Vendor: Intel (0x8086) 09:04:39 INFO - Device ID: 0x0a2e 09:04:39 INFO - Revision ID: 0x0009 09:04:39 INFO - Displays: 09:04:39 INFO - Display: 09:04:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:04:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:04:39 INFO - Display Serial Number: 1600x1200 60 09:04:39 INFO - Main Display: Yes 09:04:39 INFO - Mirror: Off 09:04:39 INFO - Online: Yes 09:04:39 INFO - Rotation: Supported 09:04:39 INFO - Return code: 0 09:04:39 INFO - Running post-action listener: _resource_record_post_action 09:04:39 INFO - [mozharness: 2017-06-15 16:04:39.019672Z] Finished run-tests step (success) 09:04:39 INFO - Running post-run listener: _resource_record_post_run 09:04:39 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 38978560; Write bytes: 559144448; Read time: 269; Write time: 1701 09:04:39 INFO - TinderboxPrint: CPU usage
53.0% 09:04:39 INFO - TinderboxPrint: I/O read bytes / time
38,978,560 / 269 09:04:39 INFO - TinderboxPrint: I/O write bytes / time
559,144,448 / 1,701 09:04:39 INFO - TinderboxPrint: CPU idle
2,359.0 (47.0%) 09:04:39 INFO - TinderboxPrint: CPU system
1,015.5 (20.2%) 09:04:39 INFO - TinderboxPrint: CPU user
1,647.2 (32.8%) 09:04:39 INFO - TinderboxPrint: Swap in / out
282,251,264 / 0 09:04:39 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 229925376; Write bytes: 226924032; Read time: 16900; Write time: 484 09:04:39 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:04:39 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 37151744; Write bytes: 312911872; Read time: 253; Write time: 1167 09:04:39 INFO - Running post-run listener: _upload_blobber_files 09:04:39 INFO - Blob upload gear active. 09:04:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:04:39 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:04:39 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'] 09:04:39 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 09:04:39 INFO - (blobuploader) - INFO - Open directory for files ... 09:04:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:04:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:04:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:41 INFO - (blobuploader) - INFO - Done attempting. 09:04:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:04:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:04:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:42 INFO - (blobuploader) - INFO - Done attempting. 09:04:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:04:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:04:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:43 INFO - (blobuploader) - INFO - Done attempting. 09:04:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:04:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:04:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:45 INFO - (blobuploader) - INFO - Done attempting. 09:04:45 INFO - (blobuploader) - INFO - Iteration through files over. 09:04:45 INFO - Return code: 0 09:04:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:04:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:04:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7bcf3fe6dede0864a1497827fdcec9236f5fd72c8a8ea4777195a8be138db04f27155013a4b03ddc99c9fabe81d1bd492323c6fb96ff0f1a840ef49a96e3581b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6a5d3e2e8ea3ce05d2f066a67000eb8ee707ac0caa80d7d26ea44e9eafb8c6d35ed2a6e35a24163a541306deb3a7f480e87c8e1ecac7a867b50ea67937521ea6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c2c79064ac22ed8c129b3d640698ee2c18afa14e4d0be0a35dd8ca82d89325928dcb47cac6ac47c877ba4dcbc39a4a3cd271ff7f4e45e35170a798ad475fbb55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bd568de8d64d0831346c4217ac981ac111a636f382fefd6c1aaf27eeebb9e08bf725aa8167013a6d8d2a02c94e53e45f1000f78da74ba8b8631d60bd50562f29"} 09:04:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:04:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:04:45 INFO - Contents: 09:04:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7bcf3fe6dede0864a1497827fdcec9236f5fd72c8a8ea4777195a8be138db04f27155013a4b03ddc99c9fabe81d1bd492323c6fb96ff0f1a840ef49a96e3581b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6a5d3e2e8ea3ce05d2f066a67000eb8ee707ac0caa80d7d26ea44e9eafb8c6d35ed2a6e35a24163a541306deb3a7f480e87c8e1ecac7a867b50ea67937521ea6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c2c79064ac22ed8c129b3d640698ee2c18afa14e4d0be0a35dd8ca82d89325928dcb47cac6ac47c877ba4dcbc39a4a3cd271ff7f4e45e35170a798ad475fbb55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bd568de8d64d0831346c4217ac981ac111a636f382fefd6c1aaf27eeebb9e08bf725aa8167013a6d8d2a02c94e53e45f1000f78da74ba8b8631d60bd50562f29"} 09:04:45 INFO - Running post-run listener: copy_logs_to_upload_dir 09:04:45 INFO - Copying logs to upload dir... 09:04:45 INFO - mkdir: /builds/slave/test/build/upload/logs 09:04:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.352859 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-15 09:04:45.149790) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-15 09:04:45.153718) ========= 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.Rym29ckRQ5/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.eBxQtSQksp/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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/7bcf3fe6dede0864a1497827fdcec9236f5fd72c8a8ea4777195a8be138db04f27155013a4b03ddc99c9fabe81d1bd492323c6fb96ff0f1a840ef49a96e3581b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6a5d3e2e8ea3ce05d2f066a67000eb8ee707ac0caa80d7d26ea44e9eafb8c6d35ed2a6e35a24163a541306deb3a7f480e87c8e1ecac7a867b50ea67937521ea6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c2c79064ac22ed8c129b3d640698ee2c18afa14e4d0be0a35dd8ca82d89325928dcb47cac6ac47c877ba4dcbc39a4a3cd271ff7f4e45e35170a798ad475fbb55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bd568de8d64d0831346c4217ac981ac111a636f382fefd6c1aaf27eeebb9e08bf725aa8167013a6d8d2a02c94e53e45f1000f78da74ba8b8631d60bd50562f29"} build_url:https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011120 build_url: 'https://queue.taskcluster.net/v1/task/EguAlGZmR--Dk3ldWxlPtw/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7bcf3fe6dede0864a1497827fdcec9236f5fd72c8a8ea4777195a8be138db04f27155013a4b03ddc99c9fabe81d1bd492323c6fb96ff0f1a840ef49a96e3581b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6a5d3e2e8ea3ce05d2f066a67000eb8ee707ac0caa80d7d26ea44e9eafb8c6d35ed2a6e35a24163a541306deb3a7f480e87c8e1ecac7a867b50ea67937521ea6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c2c79064ac22ed8c129b3d640698ee2c18afa14e4d0be0a35dd8ca82d89325928dcb47cac6ac47c877ba4dcbc39a4a3cd271ff7f4e45e35170a798ad475fbb55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bd568de8d64d0831346c4217ac981ac111a636f382fefd6c1aaf27eeebb9e08bf725aa8167013a6d8d2a02c94e53e45f1000f78da74ba8b8631d60bd50562f29"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-15 09:04:45.185601) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 09:04:45.185946) ========= 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.Rym29ckRQ5/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.eBxQtSQksp/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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.005187 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 09:04:45.234255) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-15 09:04:45.234578) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-15 09:04:45.234898) ========= ========= Total master_lag: 0.17 =========