builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0382 starttime: 1497565155.8 results: success (0) buildid: 20170615135547 builduid: cea5326f680145b0a164a39e37898899 revision: 64d650c2c2ac836530c9204ccdf372d487d912b2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-15 15:19:15.800294) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-15 15:19:15.800740) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-15 15:19:15.801013) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-15 15:19:15.825836) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 15:19:15.826220) ========= 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.Hu5JBvZJIt/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.cUlABhcNpv/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 15:19:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.1M=0.001s 2017-06-15 15:19:15 (19.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135666 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 15:19:15.986564) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 15:19:15.986883) ========= 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.Hu5JBvZJIt/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.cUlABhcNpv/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.026807 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 15:19:16.035062) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-15 15:19:16.038490) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 64d650c2c2ac836530c9204ccdf372d487d912b2 --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 64d650c2c2ac836530c9204ccdf372d487d912b2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Hu5JBvZJIt/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.cUlABhcNpv/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 15:19:16,138 truncating revision to first 12 chars 2017-06-15 15:19:16,139 Setting DEBUG logging. 2017-06-15 15:19:16,139 attempt 1/10 2017-06-15 15:19:16,139 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/64d650c2c2ac?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-15 15:19:17,264 unpacking tar archive at: mozilla-beta-64d650c2c2ac/testing/mozharness/ program finished with exit code 0 elapsedTime=1.425620 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-15 15:19:17.488423) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-15 15:19:17.488744) ========= script_repo_revision: 64d650c2c2ac836530c9204ccdf372d487d912b2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-15 15:19:17.489136) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-15 15:19:17.489411) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-15 15:19:17.506919) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-15 15:19:17.507297) ========= /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.Hu5JBvZJIt/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.cUlABhcNpv/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 15:19:17 INFO - MultiFileLogger online at 20170615 15:19:17 in /builds/slave/test 15:19:17 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 15:19:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:19:17 INFO - {'append_to_log': False, 15:19:17 INFO - 'base_work_dir': '/builds/slave/test', 15:19:17 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:19:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:19:17 INFO - 'branch': 'Mozilla-Beta', 15:19:17 INFO - 'buildbot_json_path': 'buildprops.json', 15:19:17 INFO - 'config_files': ('talos/mac_config.py',), 15:19:17 INFO - 'default_actions': ('clobber', 15:19:17 INFO - 'read-buildbot-config', 15:19:17 INFO - 'download-and-extract', 15:19:17 INFO - 'populate-webroot', 15:19:17 INFO - 'create-virtualenv', 15:19:17 INFO - 'install', 15:19:17 INFO - 'setup-mitmproxy', 15:19:17 INFO - 'run-tests'), 15:19:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:19:17 INFO - 'download_minidump_stackwalk': True, 15:19:17 INFO - 'download_symbols': 'ondemand', 15:19:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:19:17 INFO - 'tooltool.py': '/tools/tooltool.py', 15:19:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:19:17 INFO - '/tools/misc-python/virtualenv.py')}, 15:19:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:19:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:19:17 INFO - 'gecko_profile': False, 15:19:17 INFO - 'gecko_profile_interval': 0, 15:19:17 INFO - 'installer_path': 'installer.exe', 15:19:17 INFO - 'log_level': 'info', 15:19:17 INFO - 'log_name': 'talos', 15:19:17 INFO - 'log_to_console': True, 15:19:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:19:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:19:17 INFO - 'opt_config_files': (), 15:19:17 INFO - 'pip_index': False, 15:19:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:19:17 INFO - 'cmd': ('bash', 15:19:17 INFO - '-c', 15:19:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:19:17 INFO - 'enabled': True, 15:19:17 INFO - 'halt_on_failure': False, 15:19:17 INFO - 'name': 'check_screen_resolution'},), 15:19:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:19:17 INFO - 'cmd': ('bash', 15:19:17 INFO - '-c', 15:19:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:19:17 INFO - 'enabled': True, 15:19:17 INFO - 'halt_on_failure': False, 15:19:17 INFO - 'name': 'check_screen_resolution'},), 15:19:17 INFO - 'run_cmd_checks_enabled': True, 15:19:17 INFO - 'suite': 'dromaeojs-e10s', 15:19:17 INFO - 'system_bits': '32', 15:19:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:19:17 INFO - 'title': 't-yosemite-r7-0382', 15:19:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:19:17 INFO - 'use_talos_json': True, 15:19:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:19:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:19:17 INFO - 'work_dir': 'build'} 15:19:17 INFO - [mozharness: 2017-06-15 22:19:17.714892Z] Running clobber step. 15:19:17 INFO - Running pre-action listener: _resource_record_pre_action 15:19:17 INFO - Running main action method: clobber 15:19:17 INFO - rmtree: /builds/slave/test/build 15:19:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:19:20 INFO - Running post-action listener: _resource_record_post_action 15:19:20 INFO - [mozharness: 2017-06-15 22:19:20.224419Z] Finished clobber step (success) 15:19:20 INFO - [mozharness: 2017-06-15 22:19:20.224543Z] Running read-buildbot-config step. 15:19:20 INFO - Running pre-action listener: _resource_record_pre_action 15:19:20 INFO - Running main action method: read_buildbot_config 15:19:20 INFO - Using buildbot properties: 15:19:20 INFO - { 15:19:20 INFO - "project": "", 15:19:20 INFO - "product": "firefox", 15:19:20 INFO - "script_repo_revision": "production", 15:19:20 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 15:19:20 INFO - "repository": "", 15:19:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 15:19:20 INFO - "buildid": "20170615135547", 15:19:20 INFO - "pgo_build": "False", 15:19:20 INFO - "basedir": "/builds/slave/test", 15:19:20 INFO - "buildnumber": 5, 15:19:20 INFO - "slavename": "t-yosemite-r7-0382", 15:19:20 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:19:20 INFO - "platform": "yosemite_r7_devedition", 15:19:20 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 15:19:20 INFO - "branch": "mozilla-beta", 15:19:20 INFO - "repo_path": "releases/mozilla-beta", 15:19:20 INFO - "revision": "64d650c2c2ac836530c9204ccdf372d487d912b2", 15:19:20 INFO - "stage_platform": "macosx64-devedition", 15:19:20 INFO - "builduid": "cea5326f680145b0a164a39e37898899", 15:19:20 INFO - "slavebuilddir": "test" 15:19:20 INFO - } 15:19:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 15:19:20 INFO - Running post-action listener: _resource_record_post_action 15:19:20 INFO - [mozharness: 2017-06-15 22:19:20.228052Z] Finished read-buildbot-config step (success) 15:19:20 INFO - [mozharness: 2017-06-15 22:19:20.228170Z] Running download-and-extract step. 15:19:20 INFO - Running pre-action listener: _resource_record_pre_action 15:19:20 INFO - Running main action method: download_and_extract 15:19:20 INFO - mkdir: /builds/slave/test/build/tests 15:19: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')]} 15:19:20 INFO - trying https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 15:19:20 INFO - Downloading https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/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 15:19:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/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 15:19:21 INFO - Downloaded 1290 bytes. 15:19:21 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 15:19:21 INFO - Using the following test package requirements: 15:19:21 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:19:21 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 15:19:21 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 15:19:21 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:19:21 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 15:19:21 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:19:21 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 15:19:21 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:19:21 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:19:21 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 15:19:21 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 15:19:21 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:19:21 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 15:19:21 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:19:21 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 15:19:21 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:19:21 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 15:19:21 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:19:21 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 15:19:21 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 15:19:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 15:19:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 15:19:21 INFO - Fetch https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 15:19:22 INFO - Content-Length response header: 59545222 15:19:22 INFO - Bytes received: 59545222 15:19:41 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 15:19:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 15:19:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 15:19:41 INFO - Fetch https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 15:19:43 INFO - Content-Length response header: 59545222 15:19:43 INFO - Bytes received: 59545222 15:20:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 15:20:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 15:20:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 15:20:00 INFO - Content-Length response header: 14454470 15:20:00 INFO - Bytes received: 14454470 15:20:01 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')]} 15:20:01 INFO - trying https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.dmg 15:20:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 15:20:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/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 15:20:03 INFO - Downloaded 66869961 bytes. 15:20:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.dmg 15:20:03 INFO - mkdir: /builds/slave/test/properties 15:20:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:20:03 INFO - Writing to file /builds/slave/test/properties/build_url 15:20:03 INFO - Contents: 15:20:03 INFO - build_url:https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.dmg 15:20:03 INFO - Running post-action listener: _resource_record_post_action 15:20:03 INFO - Running post-action listener: set_extra_try_arguments 15:20:03 INFO - [mozharness: 2017-06-15 22:20:03.886562Z] Finished download-and-extract step (success) 15:20:03 INFO - [mozharness: 2017-06-15 22:20:03.886654Z] Running populate-webroot step. 15:20:03 INFO - Running pre-action listener: _resource_record_pre_action 15:20:03 INFO - Running main action method: populate_webroot 15:20:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:20:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:20:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:20:03 INFO - '--tppagecycles', 15:20:03 INFO - '7'], 15:20:03 INFO - 'tests': ['tsvgm']}}, 15:20:03 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:20:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:20:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:20:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:20:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:20:03 INFO - 'talos_options': ['--disable-e10s'], 15:20:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:20:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:20:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:20:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:20:03 INFO - 'talos_options': ['--disable-e10s'], 15:20:03 INFO - 'tests': ['damp', 'tps']}, 15:20:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:20:03 INFO - 'tests': ['damp', 'tps']}, 15:20:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:20:03 INFO - 'tests': ['dromaeo_dom']}, 15:20:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:20:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:20:03 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:20:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:20:03 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:20:03 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:20:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:20:03 INFO - 'tests': ['a11yr', 15:20:03 INFO - 'ts_paint', 15:20:03 INFO - 'tpaint', 15:20:03 INFO - 'sessionrestore', 15:20:03 INFO - 'sessionrestore_no_auto_restore', 15:20:03 INFO - 'tabpaint']}, 15:20:03 INFO - 'other-e10s': {'tests': ['a11yr', 15:20:03 INFO - 'ts_paint', 15:20:03 INFO - 'tpaint', 15:20:03 INFO - 'sessionrestore', 15:20:03 INFO - 'sessionrestore_no_auto_restore', 15:20:03 INFO - 'tabpaint']}, 15:20:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:20:03 INFO - 'ts_paint', 15:20:03 INFO - 'tpaint', 15:20:03 INFO - 'sessionrestore', 15:20:03 INFO - 'sessionrestore_no_auto_restore', 15:20:03 INFO - 'tabpaint']}, 15:20:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:20:03 INFO - 'ts_paint', 15:20:03 INFO - 'tpaint', 15:20:03 INFO - 'sessionrestore', 15:20:03 INFO - 'sessionrestore_no_auto_restore', 15:20:03 INFO - 'tabpaint']}, 15:20:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:20:03 INFO - 'tests': ['a11yr', 15:20:03 INFO - 'ts_paint', 15:20:03 INFO - 'tpaint', 15:20:03 INFO - 'sessionrestore', 15:20:03 INFO - 'sessionrestore_no_auto_restore', 15:20:03 INFO - 'tabpaint']}, 15:20:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:20:03 INFO - 'tests': ['a11yr', 15:20:03 INFO - 'ts_paint', 15:20:03 INFO - 'tpaint', 15:20:03 INFO - 'sessionrestore', 15:20:03 INFO - 'sessionrestore_no_auto_restore', 15:20:03 INFO - 'tabpaint']}, 15:20:03 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 15:20:03 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 15:20:03 INFO - 'bloom_basic_ref']}, 15:20:03 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:20:03 INFO - 'talos_options': ['--mitmproxy', 15:20:03 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:20:03 INFO - '--firstNonBlankPaint'], 15:20:03 INFO - 'tests': ['quantum_pageload_google', 15:20:03 INFO - 'quantum_pageload_youtube', 15:20:03 INFO - 'quantum_pageload_amazon', 15:20:03 INFO - 'quantum_pageload_facebook']}, 15:20:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:20:03 INFO - 'tests': ['tsvgx', 15:20:03 INFO - 'tsvgr_opacity', 15:20:03 INFO - 'tart', 15:20:03 INFO - 'tscrollx', 15:20:03 INFO - 'cart', 15:20:03 INFO - 'tsvg_static']}, 15:20:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:20:03 INFO - 'tsvgr_opacity', 15:20:03 INFO - 'tart', 15:20:03 INFO - 'tscrollx', 15:20:03 INFO - 'cart', 15:20:03 INFO - 'tsvg_static']}, 15:20:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:20:03 INFO - 'talos_options': ['--disable-e10s'], 15:20:03 INFO - 'tests': ['tp5o']}, 15:20:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:20:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:20:03 INFO - 'talos_options': ['--disable-e10s', 15:20:03 INFO - '--xperf_path', 15:20:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:20:03 INFO - 'tests': ['tp5n']}, 15:20:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:20:03 INFO - 'talos_options': ['--xperf_path', 15:20:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:20:03 INFO - 'tests': ['tp5n']}}, 15:20:03 INFO - 'talos.zip': {'path': '', 15:20:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:20:03 INFO - Running post-action listener: _resource_record_post_action 15:20:03 INFO - [mozharness: 2017-06-15 22:20:03.907515Z] Finished populate-webroot step (success) 15:20:03 INFO - [mozharness: 2017-06-15 22:20:03.907628Z] Running create-virtualenv step. 15:20:03 INFO - Running pre-action listener: _resource_record_pre_action 15:20:03 INFO - Running main action method: create_virtualenv 15:20:03 INFO - Creating virtualenv /builds/slave/test/build/venv 15:20:03 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 15:20:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:20:03 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:20:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:20:04 INFO - Using real prefix '/tools/python27' 15:20:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:20:04 INFO - Installing distribute.............................................................................................................................................................................................done. 15:20:07 INFO - Installing pip.................done. 15:20:07 INFO - Return code: 0 15:20:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:20:07 INFO - Reading from file tmpfile_stdout 15:20:07 INFO - Output received: 15:20:07 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) 15:20:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:20:07 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')]} 15:20:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:07 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')]} 15:20:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:07 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 0x101c1cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa13d4f910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972d30>, '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 0x10193ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101963be8>, '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.Hu5JBvZJIt/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.cUlABhcNpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 15:20:07 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 15:20:07 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 15:20:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hu5JBvZJIt/Render', 15:20:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:07 INFO - 'HOME': '/Users/cltbld', 15:20:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:07 INFO - 'LOGNAME': 'cltbld', 15:20:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:07 INFO - 'MOZ_NO_REMOTE': '1', 15:20:07 INFO - 'NO_EM_RESTART': '1', 15:20:07 INFO - 'PAGER': '/bin/cat', 15:20:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:07 INFO - 'PWD': '/builds/slave/test', 15:20:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:07 INFO - 'SHELL': '/bin/bash', 15:20:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cUlABhcNpv/Listeners', 15:20:07 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 15:20:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:07 INFO - 'USER': 'cltbld', 15:20:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:07 INFO - 'XPC_FLAGS': '0x0', 15:20:07 INFO - 'XPC_SERVICE_NAME': '0', 15:20:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:08 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:20:08 INFO - Cleaning up... 15:20:08 INFO - Return code: 0 15:20:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:20:08 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')]} 15:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:08 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')]} 15:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x101c1cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa13d4f910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972d30>, '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 0x10193ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101963be8>, '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.Hu5JBvZJIt/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.cUlABhcNpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 15:20:08 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 15:20:08 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 15:20:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hu5JBvZJIt/Render', 15:20:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:08 INFO - 'HOME': '/Users/cltbld', 15:20:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:08 INFO - 'LOGNAME': 'cltbld', 15:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:08 INFO - 'MOZ_NO_REMOTE': '1', 15:20:08 INFO - 'NO_EM_RESTART': '1', 15:20:08 INFO - 'PAGER': '/bin/cat', 15:20:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:08 INFO - 'PWD': '/builds/slave/test', 15:20:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:08 INFO - 'SHELL': '/bin/bash', 15:20:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cUlABhcNpv/Listeners', 15:20:08 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 15:20:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:08 INFO - 'USER': 'cltbld', 15:20:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:08 INFO - 'XPC_FLAGS': '0x0', 15:20:08 INFO - 'XPC_SERVICE_NAME': '0', 15:20:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:08 INFO - Downloading/unpacking psutil>=3.1.1 15:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:20:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:20:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:20:10 INFO - Installing collected packages: psutil 15:20:10 INFO - Running setup.py install for psutil 15:20:10 INFO - building 'psutil._psutil_osx' extension 15:20:10 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 15:20:10 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 15:20:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:20:11 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 15:20:11 INFO - building 'psutil._psutil_posix' extension 15:20:11 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 15:20:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:20:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:20:11 INFO - ^ 15:20:11 INFO - 1 warning generated. 15:20:11 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 15:20:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:20:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:20:11 INFO - Successfully installed psutil 15:20:11 INFO - Cleaning up... 15:20:11 INFO - Return code: 0 15:20:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:20:11 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')]} 15:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:11 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')]} 15:20:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:11 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 0x101c1cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa13d4f910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972d30>, '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 0x10193ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101963be8>, '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.Hu5JBvZJIt/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.cUlABhcNpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 15:20:11 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 15:20:11 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 15:20:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hu5JBvZJIt/Render', 15:20:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:11 INFO - 'HOME': '/Users/cltbld', 15:20:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:11 INFO - 'LOGNAME': 'cltbld', 15:20:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:11 INFO - 'MOZ_NO_REMOTE': '1', 15:20:11 INFO - 'NO_EM_RESTART': '1', 15:20:11 INFO - 'PAGER': '/bin/cat', 15:20:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:11 INFO - 'PWD': '/builds/slave/test', 15:20:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:11 INFO - 'SHELL': '/bin/bash', 15:20:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cUlABhcNpv/Listeners', 15:20:11 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 15:20:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:11 INFO - 'USER': 'cltbld', 15:20:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:11 INFO - 'XPC_FLAGS': '0x0', 15:20:11 INFO - 'XPC_SERVICE_NAME': '0', 15:20:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:20:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:20:13 INFO - Installing collected packages: mozsystemmonitor 15:20:13 INFO - Running setup.py install for mozsystemmonitor 15:20:13 INFO - Successfully installed mozsystemmonitor 15:20:13 INFO - Cleaning up... 15:20:13 INFO - Return code: 0 15:20:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:20:13 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')]} 15:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:13 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')]} 15:20:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x101c1cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa13d4f910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972d30>, '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 0x10193ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101963be8>, '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.Hu5JBvZJIt/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.cUlABhcNpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 15:20:14 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 15:20:14 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 15:20:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hu5JBvZJIt/Render', 15:20:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:14 INFO - 'HOME': '/Users/cltbld', 15:20:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:14 INFO - 'LOGNAME': 'cltbld', 15:20:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:14 INFO - 'MOZ_NO_REMOTE': '1', 15:20:14 INFO - 'NO_EM_RESTART': '1', 15:20:14 INFO - 'PAGER': '/bin/cat', 15:20:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:14 INFO - 'PWD': '/builds/slave/test', 15:20:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:14 INFO - 'SHELL': '/bin/bash', 15:20:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cUlABhcNpv/Listeners', 15:20:14 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 15:20:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:14 INFO - 'USER': 'cltbld', 15:20:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:14 INFO - 'XPC_FLAGS': '0x0', 15:20:14 INFO - 'XPC_SERVICE_NAME': '0', 15:20:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:14 INFO - Downloading/unpacking jsonschema==2.5.1 15:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:20:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:16 INFO - Downloading functools32-3.2.3-2.tar.gz 15:20:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:20:16 INFO - warning: no files found matching '*.txt' 15:20:16 INFO - no previously-included directories found matching 'build' 15:20:16 INFO - no previously-included directories found matching 'dist' 15:20:16 INFO - no previously-included directories found matching '.git*' 15:20:16 INFO - Installing collected packages: jsonschema, functools32 15:20:16 INFO - Running setup.py install for functools32 15:20:16 INFO - warning: no files found matching '*.txt' 15:20:16 INFO - no previously-included directories found matching 'build' 15:20:16 INFO - no previously-included directories found matching 'dist' 15:20:16 INFO - no previously-included directories found matching '.git*' 15:20:16 INFO - Successfully installed jsonschema functools32 15:20:16 INFO - Cleaning up... 15:20:16 INFO - Return code: 0 15:20:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:20:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101c1cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa13d4f910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972d30>, '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 0x10193ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101963be8>, '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.Hu5JBvZJIt/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.cUlABhcNpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 15:20:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:20:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:20:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hu5JBvZJIt/Render', 15:20:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:16 INFO - 'HOME': '/Users/cltbld', 15:20:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:16 INFO - 'LOGNAME': 'cltbld', 15:20:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:16 INFO - 'MOZ_NO_REMOTE': '1', 15:20:16 INFO - 'NO_EM_RESTART': '1', 15:20:16 INFO - 'PAGER': '/bin/cat', 15:20:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:16 INFO - 'PWD': '/builds/slave/test', 15:20:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:16 INFO - 'SHELL': '/bin/bash', 15:20:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cUlABhcNpv/Listeners', 15:20:16 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 15:20:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:16 INFO - 'USER': 'cltbld', 15:20:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:16 INFO - 'XPC_FLAGS': '0x0', 15:20:16 INFO - 'XPC_SERVICE_NAME': '0', 15:20:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:20:16 INFO - Cleaning up... 15:20:16 INFO - Return code: 0 15:20:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:20:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101c1cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa13d4f910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972d30>, '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 0x10193ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101963be8>, '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.Hu5JBvZJIt/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.cUlABhcNpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 15:20:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:20:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:20:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hu5JBvZJIt/Render', 15:20:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:16 INFO - 'HOME': '/Users/cltbld', 15:20:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:16 INFO - 'LOGNAME': 'cltbld', 15:20:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:16 INFO - 'MOZ_NO_REMOTE': '1', 15:20:16 INFO - 'NO_EM_RESTART': '1', 15:20:16 INFO - 'PAGER': '/bin/cat', 15:20:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:16 INFO - 'PWD': '/builds/slave/test', 15:20:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:16 INFO - 'SHELL': '/bin/bash', 15:20:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cUlABhcNpv/Listeners', 15:20:16 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 15:20:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:16 INFO - 'USER': 'cltbld', 15:20:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:16 INFO - 'XPC_FLAGS': '0x0', 15:20:16 INFO - 'XPC_SERVICE_NAME': '0', 15:20:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:17 INFO - Downloading/unpacking blobuploader==1.2.4 15:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:18 INFO - Downloading blobuploader-1.2.4.tar.gz 15:20:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:20:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:20:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:19 INFO - Downloading docopt-0.6.1.tar.gz 15:20:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:20:19 INFO - Installing collected packages: blobuploader, requests, docopt 15:20:19 INFO - Running setup.py install for blobuploader 15:20:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:20:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:20:19 INFO - Running setup.py install for requests 15:20:20 INFO - Running setup.py install for docopt 15:20:20 INFO - Successfully installed blobuploader requests docopt 15:20:20 INFO - Cleaning up... 15:20:20 INFO - Return code: 0 15:20:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20: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')]} 15:20:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20: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')]} 15:20:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x101c1cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa13d4f910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972d30>, '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 0x10193ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101963be8>, '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.Hu5JBvZJIt/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.cUlABhcNpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 15:20:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:20:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:20:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hu5JBvZJIt/Render', 15:20:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:20 INFO - 'HOME': '/Users/cltbld', 15:20:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:20 INFO - 'LOGNAME': 'cltbld', 15:20:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:20 INFO - 'MOZ_NO_REMOTE': '1', 15:20:20 INFO - 'NO_EM_RESTART': '1', 15:20:20 INFO - 'PAGER': '/bin/cat', 15:20:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:20 INFO - 'PWD': '/builds/slave/test', 15:20:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:20 INFO - 'SHELL': '/bin/bash', 15:20:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cUlABhcNpv/Listeners', 15:20:20 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 15:20:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:20 INFO - 'USER': 'cltbld', 15:20:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:20 INFO - 'XPC_FLAGS': '0x0', 15:20:20 INFO - 'XPC_SERVICE_NAME': '0', 15:20:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:20:20 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-93zDP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:20:20 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-06mf3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:20:20 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-NkUdhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:20:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Q8_Qvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:20:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-pJVDtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:20:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-J0bbl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:20:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-45O0fU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:20:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-J9JB2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:20:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-gdPOza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:20:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-a5cWso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:20:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-b0OX_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:20:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-KIx9b4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:20:21 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-WL4Jka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:20:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-FovgD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-kJRrUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:20:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-ffzEFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:20:22 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-jJE0gC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:20:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:20:22 INFO - Running setup.py install for manifestparser 15:20:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:20:22 INFO - Running setup.py install for mozcrash 15:20:22 INFO - Running setup.py install for mozdebug 15:20:22 INFO - Running setup.py install for mozdevice 15:20:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:20:23 INFO - Running setup.py install for mozfile 15:20:23 INFO - Running setup.py install for mozhttpd 15:20:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:20:23 INFO - Running setup.py install for mozinfo 15:20:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:20:23 INFO - Running setup.py install for mozInstall 15:20:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:20:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:20:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:20:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:20:23 INFO - Running setup.py install for mozleak 15:20:23 INFO - Running setup.py install for mozlog 15:20:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:20:23 INFO - Running setup.py install for moznetwork 15:20:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:20:24 INFO - Running setup.py install for mozprocess 15:20:24 INFO - Running setup.py install for mozprofile 15:20:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:20:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:20:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:20:24 INFO - Running setup.py install for mozrunner 15:20:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:20:24 INFO - Running setup.py install for mozscreenshot 15:20:24 INFO - Running setup.py install for moztest 15:20:24 INFO - Running setup.py install for mozversion 15:20:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:20:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:20:25 INFO - Cleaning up... 15:20:25 INFO - Return code: 0 15:20:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:25 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 0x101c1cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa13d4f910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972d30>, '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 0x10193ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101963be8>, '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.Hu5JBvZJIt/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.cUlABhcNpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 15:20:25 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 15:20:25 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 15:20:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hu5JBvZJIt/Render', 15:20:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:25 INFO - 'HOME': '/Users/cltbld', 15:20:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:25 INFO - 'LOGNAME': 'cltbld', 15:20:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:25 INFO - 'MOZ_NO_REMOTE': '1', 15:20:25 INFO - 'NO_EM_RESTART': '1', 15:20:25 INFO - 'PAGER': '/bin/cat', 15:20:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:25 INFO - 'PWD': '/builds/slave/test', 15:20:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:25 INFO - 'SHELL': '/bin/bash', 15:20:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cUlABhcNpv/Listeners', 15:20:25 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 15:20:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:25 INFO - 'USER': 'cltbld', 15:20:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:25 INFO - 'XPC_FLAGS': '0x0', 15:20:25 INFO - 'XPC_SERVICE_NAME': '0', 15:20:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:20:25 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-v_1Beg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:20:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:20:25 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-TA1to5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:20:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:20:25 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-xBECRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:20:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:20:25 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Qlf1vl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:20:25 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)) 15:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:20:25 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-ZW69yt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:20:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:20:25 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Z7EqS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:20:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:20:25 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-2iL4rZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:20:26 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)) 15:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:20:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-f6Jyl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:20:26 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)) 15:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:20:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-tuR_29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:20:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-0noEb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:20:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-zbNUjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:20:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-9aKvKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:20:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-vrI6Hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:20:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-vx_G50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-QyVNfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:20:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:20:26 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-iGHbjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:20:27 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)) 15:20:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:20:27 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-pdIgF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:20:27 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)) 15:20:27 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)) 15:20:27 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)) 15:20:27 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)) 15:20:27 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)) 15:20:27 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)) 15:20:27 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)) 15:20:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:20:27 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 15:20:27 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 15:20:27 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 15:20:27 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 15:20:28 INFO - Downloading blessings-1.6.tar.gz 15:20:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:20:28 INFO - Installing collected packages: blessings 15:20:28 INFO - Running setup.py install for blessings 15:20:28 INFO - Successfully installed blessings 15:20:28 INFO - Cleaning up... 15:20:28 INFO - Return code: 0 15:20:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:20:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:20:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:20:29 INFO - Reading from file tmpfile_stdout 15:20:29 INFO - Current package versions: 15:20:29 INFO - blessings == 1.6 15:20:29 INFO - blobuploader == 1.2.4 15:20:29 INFO - docopt == 0.6.1 15:20:29 INFO - functools32 == 3.2.3-2 15:20:29 INFO - jsonschema == 2.5.1 15:20:29 INFO - manifestparser == 1.1 15:20:29 INFO - mozInstall == 1.13 15:20:29 INFO - mozcrash == 1.0 15:20:29 INFO - mozdebug == 0.1 15:20:29 INFO - mozdevice == 0.51 15:20:29 INFO - mozfile == 1.2 15:20:29 INFO - mozhttpd == 0.7 15:20:29 INFO - mozinfo == 0.10 15:20:29 INFO - mozleak == 0.1 15:20:29 INFO - mozlog == 3.4 15:20:29 INFO - moznetwork == 0.27 15:20:29 INFO - mozprocess == 0.25 15:20:29 INFO - mozprofile == 0.28 15:20:29 INFO - mozrunner == 6.13 15:20:29 INFO - mozscreenshot == 0.1 15:20:29 INFO - mozsystemmonitor == 0.3 15:20:29 INFO - moztest == 0.8 15:20:29 INFO - mozversion == 1.4 15:20:29 INFO - psutil == 3.1.1 15:20:29 INFO - requests == 1.2.3 15:20:29 INFO - wsgiref == 0.1.2 15:20:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20: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')]} 15:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20: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')]} 15:20:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:29 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 0x101c1cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa13d4f910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972d30>, '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 0x10193ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101963be8>, '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.Hu5JBvZJIt/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.cUlABhcNpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 15:20:29 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 15:20:29 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 15:20:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hu5JBvZJIt/Render', 15:20:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:29 INFO - 'HOME': '/Users/cltbld', 15:20:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:29 INFO - 'LOGNAME': 'cltbld', 15:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:29 INFO - 'MOZ_NO_REMOTE': '1', 15:20:29 INFO - 'NO_EM_RESTART': '1', 15:20:29 INFO - 'PAGER': '/bin/cat', 15:20:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:29 INFO - 'PWD': '/builds/slave/test', 15:20:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:29 INFO - 'SHELL': '/bin/bash', 15:20:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cUlABhcNpv/Listeners', 15:20:29 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 15:20:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:29 INFO - 'USER': 'cltbld', 15:20:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:29 INFO - 'XPC_FLAGS': '0x0', 15:20:29 INFO - 'XPC_SERVICE_NAME': '0', 15:20:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:20:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:20:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:20:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:20:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:20:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:20:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:20:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:20:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:20:29 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:20: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 15:20: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 15:20: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 15:20: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 15:20:30 INFO - Downloading marionette_driver-1.4.0.tar.gz 15:20:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 15:20:30 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)) 15:20:30 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)) 15:20:30 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)) 15:20:30 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)) 15:20:30 INFO - Installing collected packages: marionette-driver 15:20:30 INFO - Running setup.py install for marionette-driver 15:20:31 INFO - Successfully installed marionette-driver 15:20:31 INFO - Cleaning up... 15:20:31 INFO - Return code: 0 15:20:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:20: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')]} 15:20:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20: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')]} 15:20:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:31 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 0x101c1cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa13d4f910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972d30>, '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 0x10193ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101963be8>, '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.Hu5JBvZJIt/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.cUlABhcNpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 15:20:31 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 15:20:31 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 15:20:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hu5JBvZJIt/Render', 15:20:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:31 INFO - 'HOME': '/Users/cltbld', 15:20:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:31 INFO - 'LOGNAME': 'cltbld', 15:20:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:31 INFO - 'MOZ_NO_REMOTE': '1', 15:20:31 INFO - 'NO_EM_RESTART': '1', 15:20:31 INFO - 'PAGER': '/bin/cat', 15:20:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:31 INFO - 'PWD': '/builds/slave/test', 15:20:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:31 INFO - 'SHELL': '/bin/bash', 15:20:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cUlABhcNpv/Listeners', 15:20:31 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 15:20:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:31 INFO - 'USER': 'cltbld', 15:20:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:31 INFO - 'XPC_FLAGS': '0x0', 15:20:31 INFO - 'XPC_SERVICE_NAME': '0', 15:20:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:20:31 INFO - Cleaning up... 15:20:31 INFO - Return code: 0 15:20:31 INFO - Running post-action listener: _resource_record_post_action 15:20:31 INFO - Running post-action listener: _start_resource_monitoring 15:20:31 INFO - Starting resource monitoring. 15:20:31 INFO - [mozharness: 2017-06-15 22:20:31.377707Z] Finished create-virtualenv step (success) 15:20:31 INFO - [mozharness: 2017-06-15 22:20:31.378739Z] Running install step. 15:20:31 INFO - Running pre-action listener: _resource_record_pre_action 15:20:31 INFO - Running main action method: install 15:20:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:20:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:20:31 INFO - Reading from file tmpfile_stdout 15:20:31 INFO - Detecting whether we're running mozinstall >=1.0... 15:20:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:20:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:20:31 INFO - Reading from file tmpfile_stdout 15:20:31 INFO - Output received: 15:20:31 INFO - Usage: mozinstall [options] installer 15:20:31 INFO - Options: 15:20:31 INFO - -h, --help show this help message and exit 15:20:31 INFO - -d DEST, --destination=DEST 15:20:31 INFO - Directory to install application into. [default: 15:20:31 INFO - "/builds/slave/test"] 15:20:31 INFO - --app=APP Application being installed. [default: firefox] 15:20:31 INFO - mkdir: /builds/slave/test/build/application 15:20:31 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'] 15:20:31 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 15:20:52 INFO - Reading from file tmpfile_stdout 15:20:52 INFO - Output received: 15:20:52 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:20:52 INFO - Running post-action listener: _resource_record_post_action 15:20:52 INFO - [mozharness: 2017-06-15 22:20:52.825841Z] Finished install step (success) 15:20:52 INFO - [mozharness: 2017-06-15 22:20:52.825994Z] Running setup-mitmproxy step. 15:20:52 INFO - Running pre-action listener: _resource_record_pre_action 15:20:52 INFO - Running main action method: setup_mitmproxy 15:20:52 INFO - Skipping: mitmproxy is not required 15:20:52 INFO - Running post-action listener: _resource_record_post_action 15:20:52 INFO - [mozharness: 2017-06-15 22:20:52.826639Z] Finished setup-mitmproxy step (success) 15:20:52 INFO - [mozharness: 2017-06-15 22:20:52.826775Z] Running run-tests step. 15:20:52 INFO - Running pre-action listener: _resource_record_pre_action 15:20:52 INFO - Running main action method: run_tests 15:20:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:20:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:20:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:20:52 INFO - 2017-06-15 15:20:52.841 screenresolution[1644:7617] starting screenresolution argv=screenresolution get 15:20:52 INFO - 2017-06-15 15:20:52.844 screenresolution[1644:7617] Display 0: 1600x1200x32@60 15:20:52 INFO - 2017-06-15 15:20:52.850 screenresolution[1645:7620] starting screenresolution argv=screenresolution list 15:20:52 INFO - Available Modes on Display 0 15:20:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:20:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:20:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:20:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:20:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:20:53 INFO - Intel Iris: 15:20:53 INFO - Chipset Model: Intel Iris 15:20:53 INFO - Type: GPU 15:20:53 INFO - Bus: Built-In 15:20:53 INFO - VRAM (Dynamic, Max): 1536 MB 15:20:53 INFO - Vendor: Intel (0x8086) 15:20:53 INFO - Device ID: 0x0a2e 15:20:53 INFO - Revision ID: 0x0009 15:20:53 INFO - Displays: 15:20:53 INFO - Display: 15:20:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:20:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:20:53 INFO - Display Serial Number: 1600x1200 60 15:20:53 INFO - Main Display: Yes 15:20:53 INFO - Mirror: Off 15:20:53 INFO - Online: Yes 15:20:53 INFO - Rotation: Supported 15:20:53 INFO - Return code: 0 15:20:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:20:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:20:53 INFO - Python 2.7.3 15:20:53 INFO - Return code: 0 15:20:53 INFO - grabbing minidump binary from tooltool 15:20:53 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')]} 15:20:53 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 0x101972d30>, '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 0x10193ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101963be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:20:53 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 15:20:53 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 15:20:53 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 15:20:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:20:53 INFO - Return code: 0 15:20:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:20:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:20:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:20:53 INFO - ENV: RUST_BACKTRACE is now 1 15:20:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:20:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:20:53 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/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--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 15:20:53 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/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0382 --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 15:20:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hu5JBvZJIt/Render', 15:20:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:53 INFO - 'HOME': '/Users/cltbld', 15:20:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:53 INFO - 'LOGNAME': 'cltbld', 15:20:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:20:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:53 INFO - 'MOZ_NO_REMOTE': '1', 15:20:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:20:53 INFO - 'NO_EM_RESTART': '1', 15:20:53 INFO - 'PAGER': '/bin/cat', 15:20:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:53 INFO - 'PWD': '/builds/slave/test', 15:20:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:20:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:53 INFO - 'RUST_BACKTRACE': '1', 15:20:53 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:20:53 INFO - 'SHELL': '/bin/bash', 15:20:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cUlABhcNpv/Listeners', 15:20:53 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 15:20:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:53 INFO - 'USER': 'cltbld', 15:20:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:53 INFO - 'XPC_FLAGS': '0x0', 15:20:53 INFO - 'XPC_SERVICE_NAME': '0', 15:20:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:53 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/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--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 15:20:53 INFO - mozversion application_buildid: 20170615135547 15:20:53 INFO - mozversion application_changeset: 64d650c2c2ac836530c9204ccdf372d487d912b2 15:20:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:20:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:20:53 INFO - mozversion application_name: Firefox 15:20:53 INFO - mozversion application_remotingname: firefox-dev 15:20:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:20:53 INFO - mozversion application_vendor: Mozilla 15:20:53 INFO - mozversion application_version: 55.0 15:20:53 INFO - mozversion platform_buildid: 20170615135547 15:20:53 INFO - mozversion platform_changeset: 64d650c2c2ac836530c9204ccdf372d487d912b2 15:20:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:20:53 INFO - mozversion platform_version: 55.0 15:20:53 INFO - using testdate: 1497565253 15:20:53 INFO - actual date: 1497565253 15:20:53 INFO - starting webserver on 'localhost:49232' 15:20:53 INFO - SUITE-START | Running 2 tests 15:20:53 INFO - TEST-START | dromaeo_css 15:20:53 INFO - operating with platform_type : mac_ 15:20:53 INFO - Initialising browser for dromaeo_css test... 15:20:53 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49232/getInfo.html -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpB9TQO9/profile 15:20:53 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49232/getInfo.html -foreground) 15:21:26 INFO - TEST-INFO | 1655: killed by SIGTERM 15:21:26 INFO - Browser initialized. 15:21:26 INFO - Running cycle 1/1 for dromaeo_css test... 15:21:26 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpB9TQO9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:21:28 INFO - PID 1666 | 2017-06-15 15:21:28.688 plugin-container[1667:8174] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 15:21:28 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:21:28 INFO - PID 1666 | 2017-06-15 15:21:28.688 plugin-container[1667:8174] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 15:21:28 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:21:28 INFO - PID 1666 | 2017-06-15 15:21:28.688 plugin-container[1667:8174] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:21:29 INFO - PID 1666 | 2017-06-15 15:21:29.480 plugin-container[1668:8223] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473f, name = 'com.apple.tsm.portname' 15:21:29 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:21:29 INFO - PID 1666 | 2017-06-15 15:21:29.480 plugin-container[1668:8223] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0b, name = 'com.apple.CFPasteboardClient' 15:21:29 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:21:29 INFO - PID 1666 | 2017-06-15 15:21:29.480 plugin-container[1668:8223] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:21:38 INFO - PID 1666 | RSS: Main: 214794240 15:21:38 INFO - PID 1666 | 15:24:41 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49232/tests/dromaeo/cssquery-ext.html) 15:24:44 INFO - PID 1666 | RSS: Main: 239042560 15:24:44 INFO - PID 1666 | 15:27:46 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-ext.html (next: http://localhost:49232/tests/dromaeo/cssquery-jquery.html) 15:27:47 INFO - PID 1666 | RSS: Main: 248082432 15:27:47 INFO - PID 1666 | 15:30:48 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49232/tests/dromaeo/cssquery-mootools.html) 15:30:49 INFO - PID 1666 | RSS: Main: 249692160 15:30:49 INFO - PID 1666 | 15:33:51 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49232/tests/dromaeo/cssquery-prototype.html) 15:33:51 INFO - PID 1666 | RSS: Main: 251461632 15:33:51 INFO - PID 1666 | 15:36:54 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49232/tests/dromaeo/cssquery-yui.html) 15:36:55 INFO - PID 1666 | RSS: Main: 252071936 15:36:55 INFO - PID 1666 | 15:39:56 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-yui.html (next: http://localhost:49232/tests/dromaeo/cssquery-dojo.html) 15:39:57 INFO - PID 1666 | RSS: Main: 251813888 15:39:57 INFO - PID 1666 | 15:39:57 INFO - PID 1666 | __start_tp_report 15:39:57 INFO - PID 1666 | _x_x_mozilla_page_load 15:39:57 INFO - PID 1666 | _x_x_mozilla_page_load_details 15:39:57 INFO - PID 1666 | |i|pagename|runs| 15:39:57 INFO - PID 1666 | |0;dojo.html;3563.6792688658034;3581.997040828569;3644.143626247832;3683.5211422515067;3707.5921648618646;6293.811185664432;6310.589811662245;6311.495080393569;6311.589746666464;6379;5431.158170483572;6279.120923070774;6380;6398.968005159968;6417.903731444032;5571.247881536002;5587.38538760736;5618.129189975561;5662.716864156796;5725.770969161249;5620.100783874581;5630.577706671996;5720.713964301768;5726.885462290752;5743.9712801435935;5472.179173124024;5473.890522189574;5501.807436739695;5637.436256374351;5669.149627555858;5532.723363831833;5533.972330138364;5579.581531385122;5601.131824567198;5652.830415087554;4702.3181638662545;5555.805546805843;5594.608377413582;5596.888062238773;5600.439956004408;5645.645045189166;6188.783392581283;6367;6381.425671689571;6384.074309225136;6872.312768723138;6895.275996020446;6898.379145876896;6912.4815638826785;6939.861202775966;6733.023711561845;6753.054572359873;6803.48973826965;6945.201301850301;7015.684294206773;6431;6431.614103153732;6481.611103333815;6500.122483464766;6511.120998665215;7143.785686429415;7149.678264478111;7171.713131474683;7176.677049532784;7178.2103968563415;6574.309697481791;6617.272100068989;6666.166729158856;6688.230853451818;6798.76204332846;5897.852553686193;6796.116504854337;6872.553284036521;6872.759453419107;7019.5788252705;6883.27725588806;6884.965575172192;6901;6921.134858142732;6924.446044316443;6648.235452922879;6926.376626103674;6927.099477067949;6946.861062778716;6958.582485050913;5408.459154084639;5424.83725488236;5434.375046869661;5438.401775804658;5491.945080549223;4821.614270858323;4937.925931111036;4938.975305123523;4949.727764972889;4993.976234871842;6215.440610345;6440.677966101676;6443.5167362448;6452.935470645233;6505.642189679613;7028.332308430691;7031.226565077837;7055.400290975222;7062.3996960258955;7067.151941767021;6432.807015789534;6438.45273151778;6461.547691661539;6478.319776423502;6482.222133344029;4740.525947405232;5952.76188952437;6002.309734380602;6064.666443345658;6122.173506576644;5780.508656764222;6529.412352888263;6955.23492415844;6989.231184569795;7036.683349249193;6981.720731170696;6983.860322793414;7002.614856182755;7053.717851285891;7077.079979602619;7126.35862772353;7147.2852714728115;7167.534110283023;7168.390686791681;7179.892301615376;6864.828379290558;6888.207856096613;6894.586324820527;6917.792466226021;6922.550034247758;6748;7048.400885924754;7052.0479735235085;7058.188308344606;7086.751963681247;6533.803985880431;6582.637954912526;6643.302453242533;6753.054572359775;6765.42493888025;6670.299618540177;6757.560758550679;6767.796966091025;6781.830454238683;6792.049113124265;6571.507136964651;6664.566803157779;6672.299408562225;6680.331966803281;6688.498362622726;6520.858849701189;6605.273419923708;6615.933840661532;6619.66901654925;6637.933620663925;6438.710258038306;6461.935380646133;6539.607623542603;6625.304343043911;6641.169853768279 15:39:57 INFO - PID 1666 | |1;ext.html;779.8596252674516;829.3531045784287;840.7099550655024;862.4135587800296;876.5529579914241;34570.65429345706;38444;38653.61346386535;38765.4185187222;40700.982475438126;29508.852455737746;38479.23041539168;38491;38496.42255366165;38692.806535967284;7511.023566936291;7651.043619547557;7652.19651936547;7652.502587331834;7661.693532258703;7486.475946683734;7536.4724469287175;7619.1238007629;8065.556394398306;8259.339252859787;8394.622241999094;8412;8418.536980466071;8451.57742112895;8464.576771161448;7181.856362872713;8313.459625124347;8402.45384050041;8407.915920840775;8433.536155511445;6997.510174287802;8265.917340826574;8274.503529788171;8274.793130171734;8293.419460637759;35979.280414391564;36152.81923590392;36207;36265.09337266563;36272.09823214137;3623.0761155905075;3625.583057948343;3657.7988210648405;3657.9940516358215;3663.413853783382;29556.40887182266;29827.10518684443;29897;30005.849970750005;30030;3094.5048792193143;3167.825769582672;3181.077487528637;3182.8885988990505;3212.4699424594914;244515;250427.74786126136;251725.74137129198;310863;313392;37835.810820946266;46692;46956.7652161737;47116.293255601195;47178.5282147181;48882;49320.520384388525;49330;49383.012339753004;49438;12628.431720572597;15293.541193764206;15432.614184645248;15561.844381556266;15579.987300825636;12597.559085431965;12827.230366178059;12833.871661283454;12838;12839.550615728405;11213;13283.867161328262;13287.06990510655;13308.6672833178;13309.800352994713;5153.561947234452;5828.795992140254;6001.639901605918;6042.939570604326;6102.115193297036;931.9207867331353;1000.6247657128577;1002.6190047781796;1010.525053224983;1068.4604274841156;19642.312519061765;22689.319320420414;22853.88573057124;22866.08535658588;23763.643545346833;985.4186030242048;1043.8538604595362;1099.5656715597454;1123.5168877382641;1165.4289398194824;11682.47428865686;11729.120315976335;11767.52929882795;11811.114166437552;12132.939335303266;340899.88650170265;341291;341556;341699;341963;53050.73474632602;53389.73305133449;53425.73287133617;53463.66340841432;53886.191707124424;1484.450753221315;1559.212597638208;1562.4765703489231;1567.036272692286;1575.377725798326;21701.941809509546;21978.670319945533;22561.210357637403;22570.435739106655;23645.527089458457;36849.81575092107;37101.814490927376;37333.81333093426;37373.43939840959;37391;291753.5412322925;291946;293917;294323;294617;32023.839880801377;32812.01563953085;32871.83564082164;32977.34045319128;33233.66766332403;42639.7208083758;44377;44772.32841507445;44824.55175448204;44939;27558.448831023667;28096.01663941834;29455.85272073626;30623.928162514207;31013.534796977612;26914.32714182161;30181.396372072875;30286.69713302839;30996.915107971112;31016;35083.29833403369;35662.28675426529;36048.91853244407;36372.63627363693;37172.62827371692;25541.616875746506;29607.407851843273;30057.248568785082;30179.245518862204;31546;24196.63705044461;24215.757842422056;24695;24718.505629887663;24880.37799055038 15:39:57 INFO - PID 1666 | |2;jquery.html;437.9934300985484;512.0322590304326;520.3183829183769;531.4658767938222;537.4867002013073;19044.523886902818;21290.787092129074;22056.779432205672;22307.44231394216;23075.96158172882;24078.759212407913;24162.637560436608;24265.757342426572;24460.87769561154;24535.141270055552;5244.344456942882;6404.903926441107;6423.743050278006;6462.515311351667;6465.321141280168;6341.8414539636415;6438.130852334945;6449.967750161266;6471.320511346312;6498.577592456499;5641.605087643867;5924.407559244084;6283.465905398047;6297.6536290504;6319.526035547352;6263.90604140936;6315.810525684236;6321.4942804575985;6326.145970293997;6332.1451604033555;5762.798302059407;5766.308043034863;5775.306963164405;5778.624389414675;5782.3639399666;27465.45069098607;27558;28480.145595632162;28804.279893002633;28995.565066524017;2159.265849611124;2184.934451966428;2259.051198496635;2277.271273192579;2296.2192854429572;18191.45425637233;18681.43955681332;18748.15633296505;18762.530936726555;18800.435986920413;2197.48359135603;2210.171185805323;2237.038073628339;2239.6640503924377;2270.0919632147106;161348;162418.18790906089;169164.30835691732;169278;195791;13960.790588141186;14071.226082565352;14944.850551494346;14978.550643480714;15099.245037748069;19780.20879164846;19878.90060549717;19892.403227902898;19895.502612434804;20023.799762002483;9975.700728978143;10304.278700491044;10335.793284134426;10380.740481487872;10431.217658675478;9789;9798.824141103114;9800.950995244979;9849.064338887794;9868.605255789687;8916.598753056129;9016.549172541347;9035.50304733246;9044.909550904538;9061;2662.2678763340236;2668.159529748123;2682;2683.329167708073;2821.6896141424427;917.7384445433153;949.8886303025477;951.4386511957978;1012.5139932832282;1021.5709402051157;19936.70094948577;20162.798372016387;20186.697199541726;20379.286724964553;21373.46566335854;986.2898712926682;995.4475266227313;1007.1841808135433;1027.445179603021;1053.1101219469535;9173.882983750273;10352.378857268588;10496.422696751599;10560.049595536439;10786.946065269623;563932;592072;596213;599356;607009;147634.26182869016;148091;150021;151661.24169379083;152621;979.1090108001827;985.1084768284713;1110.5224753355974;1142.3260276436943;1186.6973921650097;21030.263940762612;22786.974586143995;22811.31566053021;23032;23131.72775497364;67526.98709519263;70362;70503.32383665675;70828;71238.28761712316;66268.6686566564;89631;89865;93202.53398732963;93722;11512.424378781196;11549.307041577533;11775.882241177822;11778;11781.293122412682;14681.706365872837;18021.369252076114;18045.097745112955;18514.629707406046;18552.628947421246;12904.548340808027;14631.487897923522;14644.41422343137;14647;14758.48345307909;11338.659840204808;12445.937057779822;12646;13372.732545348845;13575.981801365135;13784.931075344559;14853.554393368217;14890.553283401516;14979;15089.170095644846;11650.592229271935;11652.475638596454;11690.941545292559;11703.356315402734;11879.821802672794;14505.202213877916;14637.414503419745;14788.334524946187;14902.85097149058;15007.774883376977 15:39:57 INFO - PID 1666 | |3;mootools.html;1240.4294024748615;1425.950091746789;1438.5971927860203;1457.709926714857;1463.626775172331;13348.532801351943;13922.373493192805;13938.51215207468;13956.2324072176;13958;13514.864851351507;13995.930020349884;14006.299685015734;14014.369353379097;14016.719665606683;2789.8326100433987;2822.20978126124;2829.476546838831;2833.9858300708465;2839.985800070997;2768;2794.9720502795017;2815.85920703965;2824.166870773127;2853.743163115319;2754.6969833318317;2788.2889863084883;2793.7206279372103;2797.5523916173415;2801.719828017192;2326.7440581536016;2734.480448714738;2768;2771.1409463066516;2771.6951135375093;2418.8427752196226;2632.197179860142;2716.1987213771786;2727.2500062482777;2745.258780129354;13469.393877275546;13772;13780.310984450738;13785.379657915419;13796.896248300112;7538.962305188494;7737.806554836118;7762.883556746653;7804.102528209215;7814.570198639072;4248.277792775205;4618.976905115486;4724.645651576111;4934.580560652349;4957.132501812204;1226.9570864764967;1402.2708191740237;1411.9435222591085;1419.6308959670457;1432.0619993904008;18971.24115035397;19500.219991200334;20625;20716.378508644764;20929.581408371898;5932.703364831741;6685.665716714145;6856.760013399507;6907.481938854606;6972.337627925339;14454.494092706705;14590.927045364706;14596.781048284285;14610.561683149523;14619.415223391157;7248.130224373003;7516.962415187889;7542.358899493494;7611.124720657195;7646.923530764732;6793;6966.9651651741415;7026.578405295699;7052.224255331909;7059.541129826544;5203.19350500673;5238.97380513095;5306.46935306474;5371.75827087782;5376.354837419534;1489.7690857917041;1524.100780539487;1524.6417091983376;1537.538738378482;1547.5589457004698;745.2361329637057;845.4969293270494;852.228732996639;853.2065179383109;884.5930871798915;6022.789202377896;7409.184989651134;7429.5542267464125;7465.104187497535;7474.626268686544;659.4856012310405;685.4208194075998;710.8969199466152;718.1489934427719;720.3552820225868;4278.187144442546;4461.843835465743;5275.57795376369;5347.679139251657;5594.86012849682;17933.551661208574;18486;18498.260069597065;18860.962647054545;18862.24551017944;16177.838221617632;17512;18022.90988545049;18634.347797827282;18811.90594047021;8440.493570385797;8592.656293748305;9121.635134594542;9603.567839447242;9668.226541876633;7356;7959.602019899097;7998.720044798404;8073.15231900642;8530.658773648984;16703.16484175762;16869.903456275304;16873.831261687716;17245.827541724422;17257.309707611555;15294.770578441556;16640.364720322978;17235.965842049518;17811.821881781016;17983.550411239823;9858.408495490294;10347.327423717434;11025.779484410426;11057.336559806437;11137.331760094421;12555.811662825248;12596.811047834106;12684.365781711062;12688.619341419771;12694.746105078031;9897.703068907944;10010.998900109931;10113.949430252802;10365.585376584851;10480.842787358044;9705.369151005161;9768.36505627132;10101.090901818872;10194.796104077728;10212.029857163558;9252.53737313145;9268.443893366419;9302.02328755471;9390.483523406278;9424.764380890532;6613.966930165318;6675.4325882300545;6786.185657721105;6984.860302794017;7041.929580704127;6732.326767323424;6899.650768066115;7176.425885929218;7221.428251310874;7366.226546212571 15:39:57 INFO - PID 1666 | |4;prototype.html;404.87246517347035;431.10115409371457;444.2226104317443;456.5138127893793;465.2509459769772;6011.639301641904;6551.410373066429;6601.966990165054;6620.735170593182;6675.232348279949;6133.723982420791;6374.968125159367;6401.175664935495;6411.230652321717;6415.230172379309;4501.77162304658;4546.976930190714;4784.521547845223;4819;4840.225563909775;4784.330193772875;4793.472718001017;4804.327394164819;4822.975885120587;4828.17920953437;4359.215341238576;4371.683008005762;4632.641335032108;4758.095961767271;4774.880627984294;3969.4442778010957;4011.1376054148354;4340.283853164256;4568.269076947706;4734.55021772931;4155.252054630166;4158.2307273154565;4177.227212965611;4187.497500299984;4200.97076216329;5445.591580631429;6215.56491045627;6240.344763799781;6255.374462553754;6257.5619706620555;5631.943680563183;5650.208970744099;5650.717464126818;5673.546116310727;5690.430956904318;5482.738970036914;6006.369331220203;6833.4191593714595;6920.238773734885;6936.861262774767;4768.785404656799;4795.6163506919365;4796.616270698336;4799.472058073609;4802.4957379475;6601.438877695402;7168.9246613007945;7187.938814569751;7315.963420182865;7380.963095184544;4044.4337792709043;4230.598093181143;4878.536539028786;4885.8289959851945;4889.3888263967;4504.7611906725915;4834.830780922651;4973.154563724175;4979.402471703346;5001.574551252948;5671.943280567224;5673.687947162945;5692.206954809222;5693.800716974886;5700.543956483471;6347.238331400262;6959.547629404073;6968.512204145763;6986.301369863075;6996.965015174993;1453.5827568120997;1554.7356949318644;1560.2978659603225;1706.223668230952;1730.2127531972922;917.2936838634214;952.9231967876256;1062.458146345354;1138.755167638945;1147.8737338892715;5715.85682863429;6211.596246243979;6346.810350429095;6400.519961002943;6434.742610295536;5991.55063370249;6747.224069232003;7120.6439678015895;7178.712851485987;7182.856342873218;5439.428859969726;5446.564274858002;6420.357964203636;6451.29035806061;6477.708503117371;3407.812570308607;3667.394879844796;3733.5893051764283;3753.8123093845197;3756.436534519844;6566.967165164144;6945.583265004116;7247.420206383476;7265.818354541073;7280.41756659466;7259.963700181571;7301.707931682673;7316;7325.963370183115;7377.668004939684;5701.890300588643;6521.934780652227;6677.332266773381;6870.587764734232;7256.9274307256255;7092.432605391453;7097.751578694927;7099;7127.786166415016;7149.428045756223;6966.133795743209;6992.860142797013;7003.649817509002;7027.648617569203;7051.717931282691;6590.7693230736695;6715.261321254756;6884.965575172092;7014.598431376509;7190.640467976685;4234.259004674232;4798.288966733784;4798.328234047166;4801.68789028712;4805.831795887128;6296.307406185224;7021.331893880293;7080.575165490087;7083;7093.32613401726;5940.524758019262;6175.6912154393;6345.7144428499905;6842.076319696777;6844.384005439534;6327.082573026793;6736.090627765189;6812.216595091628;6815.625140617313;6834.419074378733;6649.235337936199;6666.300038495888;6672.566283191577;6675.833104172434;6679.66211825857;5377.865553361197;5395.56835453155;6567.540272180901;6747.055412242192;6813.4889883257965;5824.242848429677;6647.966760166169;6651.800445986628;6688.461653819746;6698.492839111161 15:39:57 INFO - PID 1666 | |5;yui.html;749.1684230504139;842.6039761312181;852.7569642651841;870.8911386076741;874.1782724239215;16881.493555193334;17196.82803171968;17389.13054347284;17393.478195654152;17422.651546969053;13007.674808129777;13372.665683357922;13452.394642241099;13541.59375218745;13901.860981390184;936.9016253293408;945.3477100800443;955.0401846144615;957.8515330123842;958.1233171647934;976.4629453800417;977.0669011094387;981.0140808487461;981.1071924548662;983.9852402213972;1014.9898501015006;1015.6445244164557;1016.0753714120152;1017.735388798914;1021.4280003198232;1001.9949900250489;1003.8243307421172;1040.4381633917637;1045.142982754142;1045.3414348960182;733.0726630812027;750.0324581290129;750.0961341583434;751.9548827070394;753.2693287511105;17172.742408863876;17284.30862765488;17389.565260868454;17498.387556435464;17527.03601301928;17287.049212293317;17862;18151.54621134469;18162.909185454122;18278.908605456887;14645.487407940778;15114.848851511455;15164.24178791056;15191.544253672408;15194.620134496616;3567.4113771227976;3620.4207326827845;3633.8728144514816;3649.178934739689;3651.580068292154;114860.99999999917;147829;149618;152367;157193;12618.81071783924;13542.59372218835;13717.519886803913;13841.930790345983;14044.578662640137;23954;25259.62110568343;26780.330491737473;26816.06143784958;26852.59721104185;8306.750797475965;8347.040090389683;8357.456765310235;8401.747947561584;8413.200745929127;4108.198901214294;4143.97928010364;4188.455500784939;4195.853145139906;4199.748015119103;4664.720116793003;4726.763661816895;4755.358026666425;4756.1438940990265;4764.618830493552;852.4888176621116;904.1230006893303;906.8141031088611;908.3414524469706;911.7720569857536;590.5157770628044;591.7278052096084;593.964362138273;611.7002668692309;635.3106879036297;10994.670159895217;11798.351090689923;12145.878541214652;12165.513379464723;12359;575.7351618255649;590.5895402695171;600.5225845452876;607.3501353551658;612.2836281550597;5223.608229382811;5958.851028724316;6206.441420272197;6331.303556608769;6562.5406221564035;142851;144941;152125;154272;156577.21711391598;126235;127748;129235.35382323028;130266;136435;74328;80357;111144;112367;114677.85322146838;9712.368696034735;10267.94866025665;10515.106215971728;12726;12977.091603587658;26944.461110778066;36526;40084.1983160341;48316.033679326916;48949.2657610129;60840.39159608347;61852;62936.68531657312;63856.68071659612;65436.018459724095;13744.518941837388;17238.655226895644;17246.22391992338;17259.3959211427;17328.306867725652;15216.619584510476;17931;18323.54191145232;18438.35465758692;18552.81447185511;16694.332226710794;16898.66202675964;16904;16945.576360591083;17482.125893705008;16398.672026559165;16666.083365414535;16852.915735421735;17116.65766684684;17197.656046878743;16248.025118492928;16689.66620667604;16738.4141555045;16959;16979;11311.660650180507;11408.201425900108;11519.76960460803;12151.574694885636;12453.501859925502;12299.815502767287;13555.390007449885;13601.591952241448;14702.117872927658;14723.852761472677 15:39:57 INFO - PID 1666 | __end_tp_report 15:39:57 INFO - PID 1666 | __start_cc_report 15:39:57 INFO - PID 1666 | _x_x_mozilla_cycle_collect,394 15:39:57 INFO - PID 1666 | __end_cc_report 15:39:57 INFO - PID 1666 | __startTimestamp1497566397137__endTimestamp 15:39:57 INFO - PID 1666 | 15:39:57 INFO - PID 1666 | ------- Summary: start ------- 15:39:57 INFO - PID 1666 | Number of tests: 6 15:39:57 INFO - PID 1666 | 15:39:57 INFO - PID 1666 | [#0] dojo.html Cycles:165 Average:6325.18 Median:6539.61 stddev:774.39 (11.8%) stddev-sans-first:745.85 15:39:57 INFO - PID 1666 | Values: 3563.7 3582.0 3644.1 3683.5 3707.6 6293.8 6310.6 6311.5 6311.6 6379.0 5431.2 6279.1 6380.0 6399.0 6417.9 5571.2 5587.4 5618.1 5662.7 5725.8 5620.1 5630.6 5720.7 5726.9 5744.0 5472.2 5473.9 5501.8 5637.4 5669.1 5532.7 5534.0 5579.6 5601.1 5652.8 4702.3 5555.8 5594.6 5596.9 5600.4 5645.6 6188.8 6367.0 6381.4 6384.1 6872.3 6895.3 6898.4 6912.5 6939.9 6733.0 6753.1 6803.5 6945.2 7015.7 6431.0 6431.6 6481.6 6500.1 6511.1 7143.8 7149.7 7171.7 7176.7 7178.2 6574.3 6617.3 6666.2 6688.2 6798.8 5897.9 6796.1 6872.6 6872.8 7019.6 6883.3 6885.0 6901.0 6921.1 6924.4 6648.2 6926.4 6927.1 6946.9 6958.6 5408.5 5424.8 5434.4 5438.4 5491.9 4821.6 4937.9 4939.0 4949.7 4994.0 6215.4 6440.7 6443.5 6452.9 6505.6 7028.3 7031.2 7055.4 7062.4 7067.2 6432.8 6438.5 6461.5 6478.3 6482.2 4740.5 5952.8 6002.3 6064.7 6122.2 5780.5 6529.4 6955.2 6989.2 7036.7 6981.7 6983.9 7002.6 7053.7 7077.1 7126.4 7147.3 7167.5 7168.4 7179.9 6864.8 6888.2 6894.6 6917.8 6922.6 6748.0 7048.4 7052.0 7058.2 7086.8 6533.8 6582.6 6643.3 6753.1 6765.4 6670.3 6757.6 6767.8 6781.8 6792.0 6571.5 6664.6 6672.3 6680.3 6688.5 6520.9 6605.3 6615.9 6619.7 6637.9 6438.7 6461.9 6539.6 6625.3 6641.2 15:39:57 INFO - PID 1666 | 15:39:57 INFO - PID 1666 | [#1] ext.html Cycles:180 Average:44881.97 Median:341423.50 stddev:80096.32 (23.5%) stddev-sans-first:80252.57 15:39:57 INFO - PID 1666 | Values: 779.9 829.4 840.7 862.4 876.6 34570.7 38444.0 38653.6 38765.4 40701.0 29508.9 38479.2 38491.0 38496.4 38692.8 7511.0 7651.0 7652.2 7652.5 7661.7 7486.5 7536.5 7619.1 8065.6 8259.3 8394.6 8412.0 8418.5 8451.6 8464.6 7181.9 8313.5 8402.5 8407.9 8433.5 6997.5 8265.9 8274.5 8274.8 8293.4 35979.3 36152.8 36207.0 36265.1 36272.1 3623.1 3625.6 3657.8 3658.0 3663.4 29556.4 29827.1 29897.0 30005.8 30030.0 3094.5 3167.8 3181.1 3182.9 3212.5 244515.0 250427.7 251725.7 310863.0 313392.0 37835.8 46692.0 46956.8 47116.3 47178.5 48882.0 49320.5 49330.0 49383.0 49438.0 12628.4 15293.5 15432.6 15561.8 15580.0 12597.6 12827.2 12833.9 12838.0 12839.6 11213.0 13283.9 13287.1 13308.7 13309.8 5153.6 5828.8 6001.6 6042.9 6102.1 931.9 1000.6 1002.6 1010.5 1068.5 19642.3 22689.3 22853.9 22866.1 23763.6 985.4 1043.9 1099.6 1123.5 1165.4 11682.5 11729.1 11767.5 11811.1 12132.9 340899.9 341291.0 341556.0 341699.0 341963.0 53050.7 53389.7 53425.7 53463.7 53886.2 1484.5 1559.2 1562.5 1567.0 1575.4 21701.9 21978.7 22561.2 22570.4 23645.5 36849.8 37101.8 37333.8 37373.4 37391.0 291753.5 291946.0 293917.0 294323.0 294617.0 32023.8 32812.0 32871.8 32977.3 33233.7 42639.7 44377.0 44772.3 44824.6 44939.0 27558.4 28096.0 29455.9 30623.9 31013.5 26914.3 30181.4 30286.7 30996.9 31016.0 35083.3 35662.3 36048.9 36372.6 37172.6 25541.6 29607.4 30057.2 30179.2 31546.0 24196.6 24215.8 24695.0 24718.5 24880.4 15:39:57 INFO - PID 1666 | 15:39:57 INFO - PID 1666 | [#2] jquery.html Cycles:180 Average:39404.77 Median:2238.35 stddev:100943.97 (4509.7%) stddev-sans-first:101184.75 15:39:57 INFO - PID 1666 | Values: 438.0 512.0 520.3 531.5 537.5 19044.5 21290.8 22056.8 22307.4 23076.0 24078.8 24162.6 24265.8 24460.9 24535.1 5244.3 6404.9 6423.7 6462.5 6465.3 6341.8 6438.1 6450.0 6471.3 6498.6 5641.6 5924.4 6283.5 6297.7 6319.5 6263.9 6315.8 6321.5 6326.1 6332.1 5762.8 5766.3 5775.3 5778.6 5782.4 27465.5 27558.0 28480.1 28804.3 28995.6 2159.3 2184.9 2259.1 2277.3 2296.2 18191.5 18681.4 18748.2 18762.5 18800.4 2197.5 2210.2 2237.0 2239.7 2270.1 161348.0 162418.2 169164.3 169278.0 195791.0 13960.8 14071.2 14944.9 14978.6 15099.2 19780.2 19878.9 19892.4 19895.5 20023.8 9975.7 10304.3 10335.8 10380.7 10431.2 9789.0 9798.8 9801.0 9849.1 9868.6 8916.6 9016.5 9035.5 9044.9 9061.0 2662.3 2668.2 2682.0 2683.3 2821.7 917.7 949.9 951.4 1012.5 1021.6 19936.7 20162.8 20186.7 20379.3 21373.5 986.3 995.4 1007.2 1027.4 1053.1 9173.9 10352.4 10496.4 10560.0 10786.9 563932.0 592072.0 596213.0 599356.0 607009.0 147634.3 148091.0 150021.0 151661.2 152621.0 979.1 985.1 1110.5 1142.3 1186.7 21030.3 22787.0 22811.3 23032.0 23131.7 67527.0 70362.0 70503.3 70828.0 71238.3 66268.7 89631.0 89865.0 93202.5 93722.0 11512.4 11549.3 11775.9 11778.0 11781.3 14681.7 18021.4 18045.1 18514.6 18552.6 12904.5 14631.5 14644.4 14647.0 14758.5 11338.7 12445.9 12646.0 13372.7 13576.0 13784.9 14853.6 14890.6 14979.0 15089.2 11650.6 11652.5 11690.9 11703.4 11879.8 14505.2 14637.4 14788.3 14902.9 15007.8 15:39:57 INFO - PID 1666 | 15:39:57 INFO - PID 1666 | [#3] mootools.html Cycles:180 Average:8359.67 Median:2794.35 stddev:5560.57 (199.0%) stddev-sans-first:5550.43 15:39:57 INFO - PID 1666 | Values: 1240.4 1426.0 1438.6 1457.7 1463.6 13348.5 13922.4 13938.5 13956.2 13958.0 13514.9 13995.9 14006.3 14014.4 14016.7 2789.8 2822.2 2829.5 2834.0 2840.0 2768.0 2795.0 2815.9 2824.2 2853.7 2754.7 2788.3 2793.7 2797.6 2801.7 2326.7 2734.5 2768.0 2771.1 2771.7 2418.8 2632.2 2716.2 2727.3 2745.3 13469.4 13772.0 13780.3 13785.4 13796.9 7539.0 7737.8 7762.9 7804.1 7814.6 4248.3 4619.0 4724.6 4934.6 4957.1 1227.0 1402.3 1411.9 1419.6 1432.1 18971.2 19500.2 20625.0 20716.4 20929.6 5932.7 6685.7 6856.8 6907.5 6972.3 14454.5 14590.9 14596.8 14610.6 14619.4 7248.1 7517.0 7542.4 7611.1 7646.9 6793.0 6967.0 7026.6 7052.2 7059.5 5203.2 5239.0 5306.5 5371.8 5376.4 1489.8 1524.1 1524.6 1537.5 1547.6 745.2 845.5 852.2 853.2 884.6 6022.8 7409.2 7429.6 7465.1 7474.6 659.5 685.4 710.9 718.1 720.4 4278.2 4461.8 5275.6 5347.7 5594.9 17933.6 18486.0 18498.3 18861.0 18862.2 16177.8 17512.0 18022.9 18634.3 18811.9 8440.5 8592.7 9121.6 9603.6 9668.2 7356.0 7959.6 7998.7 8073.2 8530.7 16703.2 16869.9 16873.8 17245.8 17257.3 15294.8 16640.4 17236.0 17811.8 17983.6 9858.4 10347.3 11025.8 11057.3 11137.3 12555.8 12596.8 12684.4 12688.6 12694.7 9897.7 10011.0 10113.9 10365.6 10480.8 9705.4 9768.4 10101.1 10194.8 10212.0 9252.5 9268.4 9302.0 9390.5 9424.8 6614.0 6675.4 6786.2 6984.9 7041.9 6732.3 6899.7 7176.4 7221.4 7366.2 15:39:57 INFO - PID 1666 | 15:39:57 INFO - PID 1666 | [#4] prototype.html Cycles:180 Average:5540.11 Median:6247.86 stddev:1714.64 (27.4%) stddev-sans-first:1675.57 15:39:57 INFO - PID 1666 | Values: 404.9 431.1 444.2 456.5 465.3 6011.6 6551.4 6602.0 6620.7 6675.2 6133.7 6375.0 6401.2 6411.2 6415.2 4501.8 4547.0 4784.5 4819.0 4840.2 4784.3 4793.5 4804.3 4823.0 4828.2 4359.2 4371.7 4632.6 4758.1 4774.9 3969.4 4011.1 4340.3 4568.3 4734.6 4155.3 4158.2 4177.2 4187.5 4201.0 5445.6 6215.6 6240.3 6255.4 6257.6 5631.9 5650.2 5650.7 5673.5 5690.4 5482.7 6006.4 6833.4 6920.2 6936.9 4768.8 4795.6 4796.6 4799.5 4802.5 6601.4 7168.9 7187.9 7316.0 7381.0 4044.4 4230.6 4878.5 4885.8 4889.4 4504.8 4834.8 4973.2 4979.4 5001.6 5671.9 5673.7 5692.2 5693.8 5700.5 6347.2 6959.5 6968.5 6986.3 6997.0 1453.6 1554.7 1560.3 1706.2 1730.2 917.3 952.9 1062.5 1138.8 1147.9 5715.9 6211.6 6346.8 6400.5 6434.7 5991.6 6747.2 7120.6 7178.7 7182.9 5439.4 5446.6 6420.4 6451.3 6477.7 3407.8 3667.4 3733.6 3753.8 3756.4 6567.0 6945.6 7247.4 7265.8 7280.4 7260.0 7301.7 7316.0 7326.0 7377.7 5701.9 6521.9 6677.3 6870.6 7256.9 7092.4 7097.8 7099.0 7127.8 7149.4 6966.1 6992.9 7003.6 7027.6 7051.7 6590.8 6715.3 6885.0 7014.6 7190.6 4234.3 4798.3 4798.3 4801.7 4805.8 6296.3 7021.3 7080.6 7083.0 7093.3 5940.5 6175.7 6345.7 6842.1 6844.4 6327.1 6736.1 6812.2 6815.6 6834.4 6649.2 6666.3 6672.6 6675.8 6679.7 5377.9 5395.6 6567.5 6747.1 6813.5 5824.2 6648.0 6651.8 6688.5 6698.5 15:39:57 INFO - PID 1666 | 15:39:57 INFO - PID 1666 | [#5] yui.html Cycles:180 Average:25414.19 Median:17452.39 stddev:40031.54 (229.4%) stddev-sans-first:40101.00 15:39:57 INFO - PID 1666 | Values: 749.2 842.6 852.8 870.9 874.2 16881.5 17196.8 17389.1 17393.5 17422.7 13007.7 13372.7 13452.4 13541.6 13901.9 936.9 945.3 955.0 957.9 958.1 976.5 977.1 981.0 981.1 984.0 1015.0 1015.6 1016.1 1017.7 1021.4 1002.0 1003.8 1040.4 1045.1 1045.3 733.1 750.0 750.1 752.0 753.3 17172.7 17284.3 17389.6 17498.4 17527.0 17287.0 17862.0 18151.5 18162.9 18278.9 14645.5 15114.8 15164.2 15191.5 15194.6 3567.4 3620.4 3633.9 3649.2 3651.6 114861.0 147829.0 149618.0 152367.0 157193.0 12618.8 13542.6 13717.5 13841.9 14044.6 23954.0 25259.6 26780.3 26816.1 26852.6 8306.8 8347.0 8357.5 8401.7 8413.2 4108.2 4144.0 4188.5 4195.9 4199.7 4664.7 4726.8 4755.4 4756.1 4764.6 852.5 904.1 906.8 908.3 911.8 590.5 591.7 594.0 611.7 635.3 10994.7 11798.4 12145.9 12165.5 12359.0 575.7 590.6 600.5 607.4 612.3 5223.6 5958.9 6206.4 6331.3 6562.5 142851.0 144941.0 152125.0 154272.0 156577.2 126235.0 127748.0 129235.4 130266.0 136435.0 74328.0 80357.0 111144.0 112367.0 114677.9 9712.4 10267.9 10515.1 12726.0 12977.1 26944.5 36526.0 40084.2 48316.0 48949.3 60840.4 61852.0 62936.7 63856.7 65436.0 13744.5 17238.7 17246.2 17259.4 17328.3 15216.6 17931.0 18323.5 18438.4 18552.8 16694.3 16898.7 16904.0 16945.6 17482.1 16398.7 16666.1 16852.9 17116.7 17197.7 16248.0 16689.7 16738.4 16959.0 16979.0 11311.7 11408.2 11519.8 12151.6 12453.5 12299.8 13555.4 13601.6 14702.1 14723.9 15:39:57 INFO - PID 1666 | -------- Summary: end -------- 15:39:57 INFO - PID 1666 | 15:39:57 INFO - TEST-INFO | 1666: exit 0 15:39:57 INFO - TEST-OK | dromaeo_css | took 1144268ms 15:39:57 INFO - TEST-START | kraken 15:39:57 INFO - operating with platform_type : mac_ 15:39:57 INFO - Initialising browser for kraken test... 15:39:57 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49232/getInfo.html -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpk6DMY_/profile 15:39:57 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49232/getInfo.html -foreground) 15:40:31 INFO - TEST-INFO | 1678: killed by SIGTERM 15:40:31 INFO - Browser initialized. 15:40:31 INFO - Running cycle 1/1 for kraken test... 15:40:31 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpk6DMY_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:40:32 INFO - PID 1682 | 2017-06-15 15:40:32.955 plugin-container[1683:10798] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9443, name = 'com.apple.tsm.portname' 15:40:32 INFO - PID 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:40:32 INFO - PID 1682 | 2017-06-15 15:40:32.955 plugin-container[1683:10798] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4417, name = 'com.apple.CFPasteboardClient' 15:40:32 INFO - PID 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:40:32 INFO - PID 1682 | 2017-06-15 15:40:32.955 plugin-container[1683:10798] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:40:33 INFO - PID 1682 | 2017-06-15 15:40:33.754 plugin-container[1684:10839] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 15:40:33 INFO - PID 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:40:33 INFO - PID 1682 | 2017-06-15 15:40:33.755 plugin-container[1684:10839] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9817, name = 'com.apple.CFPasteboardClient' 15:40:33 INFO - PID 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:40:33 INFO - PID 1682 | 2017-06-15 15:40:33.755 plugin-container[1684:10839] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:40:43 INFO - PID 1682 | RSS: Main: 214237184 15:40:43 INFO - PID 1682 | 15:40:46 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-beat-detection) 15:40:46 INFO - PID 1682 | RSS: Main: 228331520 15:40:46 INFO - PID 1682 | 15:40:49 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-dft) 15:40:50 INFO - PID 1682 | RSS: Main: 238567424 15:40:50 INFO - PID 1682 | 15:40:53 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-fft) 15:40:54 INFO - PID 1682 | RSS: Main: 242085888 15:40:54 INFO - PID 1682 | 15:40:56 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-oscillator) 15:40:56 INFO - PID 1682 | RSS: Main: 242262016 15:40:56 INFO - PID 1682 | 15:40:58 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:40:59 INFO - PID 1682 | RSS: Main: 244776960 15:40:59 INFO - PID 1682 | 15:41:02 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-darkroom) 15:41:02 INFO - PID 1682 | RSS: Main: 249495552 15:41:02 INFO - PID 1682 | 15:41:05 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-desaturate) 15:41:06 INFO - PID 1682 | RSS: Main: 249872384 15:41:06 INFO - PID 1682 | 15:41:08 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49232/tests/kraken/driver.html?testName=json-parse-financial) 15:41:09 INFO - PID 1682 | RSS: Main: 249765888 15:41:09 INFO - PID 1682 | 15:41:11 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49232/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:41:11 INFO - PID 1682 | RSS: Main: 249417728 15:41:11 INFO - PID 1682 | 15:41:13 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:41:14 INFO - PID 1682 | RSS: Main: 249458688 15:41:14 INFO - PID 1682 | 15:41:16 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:41:16 INFO - PID 1682 | RSS: Main: 249425920 15:41:16 INFO - PID 1682 | 15:41:19 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:41:20 INFO - PID 1682 | RSS: Main: 249597952 15:41:20 INFO - PID 1682 | 15:41:23 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:41:24 INFO - PID 1682 | RSS: Main: 249892864 15:41:24 INFO - PID 1682 | 15:41:25 INFO - PID 1682 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49232/tests/kraken/driver.html?testName=ai-astar) 15:41:26 INFO - PID 1682 | RSS: Main: 251494400 15:41:26 INFO - PID 1682 | 15:41:26 INFO - PID 1682 | __start_tp_report 15:41:26 INFO - PID 1682 | _x_x_mozilla_page_load 15:41:26 INFO - PID 1682 | _x_x_mozilla_page_load_details 15:41:26 INFO - PID 1682 | |i|pagename|runs| 15:41:26 INFO - PID 1682 | |0;ai-astar;133;171;123;113;163;96;157;81;132;180 15:41:26 INFO - PID 1682 | |1;audio-beat-detection;150;99;169;189;96;160;136;175;161;114 15:41:26 INFO - PID 1682 | |2;audio-dft;193;205;199;205;197;198;196;203;207;197 15:41:26 INFO - PID 1682 | |3;audio-fft;65;79;79;159;56;58;59;61;126;58 15:41:26 INFO - PID 1682 | |4;audio-oscillator;67;69;119;68;69;71;107;70;70;115 15:41:26 INFO - PID 1682 | |5;imaging-gaussian-blur;90;90;91;90;91;89;89;95;90;90 15:41:26 INFO - PID 1682 | |6;imaging-darkroom;97;98;96;97;97;98;96;97;96;97 15:41:26 INFO - PID 1682 | |7;imaging-desaturate;91;92;89;92;93;91;91;92;89;88 15:41:26 INFO - PID 1682 | |8;json-parse-financial;50;49;50;49;55;79;77;56;52;52 15:41:26 INFO - PID 1682 | |9;json-stringify-tinderbox;54;55;55;58;62;61;60;57;56;54 15:41:26 INFO - PID 1682 | |10;stanford-crypto-aes;81;80;77;90;99;80;79;80;82;71 15:41:26 INFO - PID 1682 | |11;stanford-crypto-ccm;139;143;145;142;134;136;139;142;136;179 15:41:26 INFO - PID 1682 | |12;stanford-crypto-pbkdf2;172;166;204;224;169;226;209;181;223;224 15:41:26 INFO - PID 1682 | |13;stanford-crypto-sha256-iterative;67;68;66;65;66;95;93;90;92;72 15:41:26 INFO - PID 1682 | __end_tp_report 15:41:26 INFO - PID 1682 | __start_cc_report 15:41:26 INFO - PID 1682 | _x_x_mozilla_cycle_collect,1028 15:41:26 INFO - PID 1682 | __end_cc_report 15:41:26 INFO - PID 1682 | __startTimestamp1497566486157__endTimestamp 15:41:26 INFO - PID 1682 | 15:41:26 INFO - PID 1682 | ------- Summary: start ------- 15:41:26 INFO - PID 1682 | Number of tests: 14 15:41:26 INFO - PID 1682 | 15:41:26 INFO - PID 1682 | [#0] ai-astar Cycles:10 Average:134.90 Median:167.00 stddev:32.77 (19.6%) stddev-sans-first:34.75 15:41:26 INFO - PID 1682 | Values: 133.0 171.0 123.0 113.0 163.0 96.0 157.0 81.0 132.0 180.0 15:41:26 INFO - PID 1682 | 15:41:26 INFO - PID 1682 | [#1] audio-beat-detection Cycles:10 Average:144.90 Median:172.00 stddev:32.45 (18.9%) stddev-sans-first:34.37 15:41:26 INFO - PID 1682 | Values: 150.0 99.0 169.0 189.0 96.0 160.0 136.0 175.0 161.0 114.0 15:41:26 INFO - PID 1682 | 15:41:26 INFO - PID 1682 | [#2] audio-dft Cycles:10 Average:200.00 Median:201.00 stddev:4.67 (2.3%) stddev-sans-first:4.21 15:41:26 INFO - PID 1682 | Values: 193.0 205.0 199.0 205.0 197.0 198.0 196.0 203.0 207.0 197.0 15:41:26 INFO - PID 1682 | 15:41:26 INFO - PID 1682 | [#3] audio-fft Cycles:10 Average:80.00 Median:60.00 stddev:34.85 (58.1%) stddev-sans-first:36.54 15:41:26 INFO - PID 1682 | Values: 65.0 79.0 79.0 159.0 56.0 58.0 59.0 61.0 126.0 58.0 15:41:26 INFO - PID 1682 | 15:41:26 INFO - PID 1682 | [#4] audio-oscillator Cycles:10 Average:82.50 Median:69.00 stddev:21.73 (31.5%) stddev-sans-first:22.31 15:41:26 INFO - PID 1682 | Values: 67.0 69.0 119.0 68.0 69.0 71.0 107.0 70.0 70.0 115.0 15:41:26 INFO - PID 1682 | 15:41:26 INFO - PID 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:90.00 stddev:1.72 (1.9%) stddev-sans-first:1.81 15:41:26 INFO - PID 1682 | Values: 90.0 90.0 91.0 90.0 91.0 89.0 89.0 95.0 90.0 90.0 15:41:26 INFO - PID 1682 | 15:41:26 INFO - PID 1682 | [#6] imaging-darkroom Cycles:10 Average:96.90 Median:97.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 15:41:26 INFO - PID 1682 | Values: 97.0 98.0 96.0 97.0 97.0 98.0 96.0 97.0 96.0 97.0 15:41:26 INFO - PID 1682 | 15:41:26 INFO - PID 1682 | [#7] imaging-desaturate Cycles:10 Average:90.80 Median:91.50 stddev:1.62 (1.8%) stddev-sans-first:1.72 15:41:26 INFO - PID 1682 | Values: 91.0 92.0 89.0 92.0 93.0 91.0 91.0 92.0 89.0 88.0 15:41:26 INFO - PID 1682 | 15:41:26 INFO - PID 1682 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:53.50 stddev:11.38 (21.3%) stddev-sans-first:11.79 15:41:26 INFO - PID 1682 | Values: 50.0 49.0 50.0 49.0 55.0 79.0 77.0 56.0 52.0 52.0 15:41:26 INFO - PID 1682 | 15:41:26 INFO - PID 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:57.20 Median:57.50 stddev:2.94 (5.1%) stddev-sans-first:2.88 15:41:26 INFO - PID 1682 | Values: 54.0 55.0 55.0 58.0 62.0 61.0 60.0 57.0 56.0 54.0 15:41:26 INFO - PID 1682 | 15:41:26 INFO - PID 1682 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:80.50 stddev:7.61 (9.5%) stddev-sans-first:8.06 15:41:26 INFO - PID 1682 | Values: 81.0 80.0 77.0 90.0 99.0 80.0 79.0 80.0 82.0 71.0 15:41:26 INFO - PID 1682 | 15:41:26 INFO - PID 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:143.50 Median:142.00 stddev:12.96 (9.1%) stddev-sans-first:13.64 15:41:26 INFO - PID 1682 | Values: 139.0 143.0 145.0 142.0 134.0 136.0 139.0 142.0 136.0 179.0 15:41:26 INFO - PID 1682 | 15:41:26 INFO - PID 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.80 Median:216.00 stddev:25.20 (11.7%) stddev-sans-first:24.64 15:41:26 INFO - PID 1682 | Values: 172.0 166.0 204.0 224.0 169.0 226.0 209.0 181.0 223.0 224.0 15:41:26 INFO - PID 1682 | 15:41:26 INFO - PID 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.40 Median:81.00 stddev:13.18 (16.3%) stddev-sans-first:13.44 15:41:26 INFO - PID 1682 | Values: 67.0 68.0 66.0 65.0 66.0 95.0 93.0 90.0 92.0 72.0 15:41:26 INFO - PID 1682 | -------- Summary: end -------- 15:41:26 INFO - PID 1682 | 15:41:27 INFO - TEST-INFO | 1682: exit 0 15:41:27 INFO - TEST-OK | kraken | took 89329ms 15:41:27 INFO - SUITE-END | took 1233s 15:41:27 INFO - Completed test suite (00:20:33) 15:41:27 INFO - javascript benchmark 15:41:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8319.609954928965, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3563.6792688658034, 3581.997040828569, 3644.143626247832, 3683.5211422515067, 3707.5921648618646, 6293.811185664432, 6310.589811662245, 6311.495080393569, 6311.589746666464, 6379.0, 5431.158170483572, 6279.120923070774, 6380.0, 6398.968005159968, 6417.903731444032, 5571.247881536002, 5587.38538760736, 5618.129189975561, 5662.716864156796, 5725.770969161249, 5620.100783874581, 5630.577706671996, 5720.713964301768, 5726.885462290752, 5743.9712801435935, 5472.179173124024, 5473.890522189574, 5501.807436739695, 5637.436256374351, 5669.149627555858, 5532.723363831833, 5533.972330138364, 5579.581531385122, 5601.131824567198, 5652.830415087554, 4702.3181638662545, 5555.805546805843, 5594.608377413582, 5596.888062238773, 5600.439956004408, 5645.645045189166, 6188.783392581283, 6367.0, 6381.425671689571, 6384.074309225136, 6872.312768723138, 6895.275996020446, 6898.379145876896, 6912.4815638826785, 6939.861202775966, 6733.023711561845, 6753.054572359873, 6803.48973826965, 6945.201301850301, 7015.684294206773, 6431.0, 6431.614103153732, 6481.611103333815, 6500.122483464766, 6511.120998665215, 7143.785686429415, 7149.678264478111, 7171.713131474683, 7176.677049532784, 7178.2103968563415, 6574.309697481791, 6617.272100068989, 6666.166729158856, 6688.230853451818, 6798.76204332846, 5897.852553686193, 6796.116504854337, 6872.553284036521, 6872.759453419107, 7019.5788252705, 6883.27725588806, 6884.965575172192, 6901.0, 6921.134858142732, 6924.446044316443, 6648.235452922879, 6926.376626103674, 6927.099477067949, 6946.861062778716, 6958.582485050913, 5408.459154084639, 5424.83725488236, 5434.375046869661, 5438.401775804658, 5491.945080549223, 4821.614270858323, 4937.925931111036, 4938.975305123523, 4949.727764972889, 4993.976234871842, 6215.440610345, 6440.677966101676, 6443.5167362448, 6452.935470645233, 6505.642189679613, 7028.332308430691, 7031.226565077837, 7055.400290975222, 7062.3996960258955, 7067.151941767021, 6432.807015789534, 6438.45273151778, 6461.547691661539, 6478.319776423502, 6482.222133344029, 4740.525947405232, 5952.76188952437, 6002.309734380602, 6064.666443345658, 6122.173506576644, 5780.508656764222, 6529.412352888263, 6955.23492415844, 6989.231184569795, 7036.683349249193, 6981.720731170696, 6983.860322793414, 7002.614856182755, 7053.717851285891, 7077.079979602619, 7126.35862772353, 7147.2852714728115, 7167.534110283023, 7168.390686791681, 7179.892301615376, 6864.828379290558, 6888.207856096613, 6894.586324820527, 6917.792466226021, 6922.550034247758, 6748.0, 7048.400885924754, 7052.0479735235085, 7058.188308344606, 7086.751963681247, 6533.803985880431, 6582.637954912526, 6643.302453242533, 6753.054572359775, 6765.42493888025, 6670.299618540177, 6757.560758550679, 6767.796966091025, 6781.830454238683, 6792.049113124265, 6571.507136964651, 6664.566803157779, 6672.299408562225, 6680.331966803281, 6688.498362622726, 6520.858849701189, 6605.273419923708, 6615.933840661532, 6619.66901654925, 6637.933620663925, 6438.710258038306, 6461.935380646133, 6539.607623542603, 6625.304343043911, 6641.169853768279], "value": 6272.760196223469, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [779.8596252674516, 829.3531045784287, 840.7099550655024, 862.4135587800296, 876.5529579914241, 34570.65429345706, 38444.0, 38653.61346386535, 38765.4185187222, 40700.982475438126, 29508.852455737746, 38479.23041539168, 38491.0, 38496.42255366165, 38692.806535967284, 7511.023566936291, 7651.043619547557, 7652.19651936547, 7652.502587331834, 7661.693532258703, 7486.475946683734, 7536.4724469287175, 7619.1238007629, 8065.556394398306, 8259.339252859787, 8394.622241999094, 8412.0, 8418.536980466071, 8451.57742112895, 8464.576771161448, 7181.856362872713, 8313.459625124347, 8402.45384050041, 8407.915920840775, 8433.536155511445, 6997.510174287802, 8265.917340826574, 8274.503529788171, 8274.793130171734, 8293.419460637759, 35979.280414391564, 36152.81923590392, 36207.0, 36265.09337266563, 36272.09823214137, 3623.0761155905075, 3625.583057948343, 3657.7988210648405, 3657.9940516358215, 3663.413853783382, 29556.40887182266, 29827.10518684443, 29897.0, 30005.849970750005, 30030.0, 3094.5048792193143, 3167.825769582672, 3181.077487528637, 3182.8885988990505, 3212.4699424594914, 244515.0, 250427.74786126136, 251725.74137129198, 310863.0, 313392.0, 37835.810820946266, 46692.0, 46956.7652161737, 47116.293255601195, 47178.5282147181, 48882.0, 49320.520384388525, 49330.0, 49383.012339753004, 49438.0, 12628.431720572597, 15293.541193764206, 15432.614184645248, 15561.844381556266, 15579.987300825636, 12597.559085431965, 12827.230366178059, 12833.871661283454, 12838.0, 12839.550615728405, 11213.0, 13283.867161328262, 13287.06990510655, 13308.6672833178, 13309.800352994713, 5153.561947234452, 5828.795992140254, 6001.639901605918, 6042.939570604326, 6102.115193297036, 931.9207867331353, 1000.6247657128577, 1002.6190047781796, 1010.525053224983, 1068.4604274841156, 19642.312519061765, 22689.319320420414, 22853.88573057124, 22866.08535658588, 23763.643545346833, 985.4186030242048, 1043.8538604595362, 1099.5656715597454, 1123.5168877382641, 1165.4289398194824, 11682.47428865686, 11729.120315976335, 11767.52929882795, 11811.114166437552, 12132.939335303266, 340899.88650170265, 341291.0, 341556.0, 341699.0, 341963.0, 53050.73474632602, 53389.73305133449, 53425.73287133617, 53463.66340841432, 53886.191707124424, 1484.450753221315, 1559.212597638208, 1562.4765703489231, 1567.036272692286, 1575.377725798326, 21701.941809509546, 21978.670319945533, 22561.210357637403, 22570.435739106655, 23645.527089458457, 36849.81575092107, 37101.814490927376, 37333.81333093426, 37373.43939840959, 37391.0, 291753.5412322925, 291946.0, 293917.0, 294323.0, 294617.0, 32023.839880801377, 32812.01563953085, 32871.83564082164, 32977.34045319128, 33233.66766332403, 42639.7208083758, 44377.0, 44772.32841507445, 44824.55175448204, 44939.0, 27558.448831023667, 28096.01663941834, 29455.85272073626, 30623.928162514207, 31013.534796977612, 26914.32714182161, 30181.396372072875, 30286.69713302839, 30996.915107971112, 31016.0, 35083.29833403369, 35662.28675426529, 36048.91853244407, 36372.63627363693, 37172.62827371692, 25541.616875746506, 29607.407851843273, 30057.248568785082, 30179.245518862204, 31546.0, 24196.63705044461, 24215.757842422056, 24695.0, 24718.505629887663, 24880.37799055038], "value": 17161.65643379085, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [437.9934300985484, 512.0322590304326, 520.3183829183769, 531.4658767938222, 537.4867002013073, 19044.523886902818, 21290.787092129074, 22056.779432205672, 22307.44231394216, 23075.96158172882, 24078.759212407913, 24162.637560436608, 24265.757342426572, 24460.87769561154, 24535.141270055552, 5244.344456942882, 6404.903926441107, 6423.743050278006, 6462.515311351667, 6465.321141280168, 6341.8414539636415, 6438.130852334945, 6449.967750161266, 6471.320511346312, 6498.577592456499, 5641.605087643867, 5924.407559244084, 6283.465905398047, 6297.6536290504, 6319.526035547352, 6263.90604140936, 6315.810525684236, 6321.4942804575985, 6326.145970293997, 6332.1451604033555, 5762.798302059407, 5766.308043034863, 5775.306963164405, 5778.624389414675, 5782.3639399666, 27465.45069098607, 27558.0, 28480.145595632162, 28804.279893002633, 28995.565066524017, 2159.265849611124, 2184.934451966428, 2259.051198496635, 2277.271273192579, 2296.2192854429572, 18191.45425637233, 18681.43955681332, 18748.15633296505, 18762.530936726555, 18800.435986920413, 2197.48359135603, 2210.171185805323, 2237.038073628339, 2239.6640503924377, 2270.0919632147106, 161348.0, 162418.18790906089, 169164.30835691732, 169278.0, 195791.0, 13960.790588141186, 14071.226082565352, 14944.850551494346, 14978.550643480714, 15099.245037748069, 19780.20879164846, 19878.90060549717, 19892.403227902898, 19895.502612434804, 20023.799762002483, 9975.700728978143, 10304.278700491044, 10335.793284134426, 10380.740481487872, 10431.217658675478, 9789.0, 9798.824141103114, 9800.950995244979, 9849.064338887794, 9868.605255789687, 8916.598753056129, 9016.549172541347, 9035.50304733246, 9044.909550904538, 9061.0, 2662.2678763340236, 2668.159529748123, 2682.0, 2683.329167708073, 2821.6896141424427, 917.7384445433153, 949.8886303025477, 951.4386511957978, 1012.5139932832282, 1021.5709402051157, 19936.70094948577, 20162.798372016387, 20186.697199541726, 20379.286724964553, 21373.46566335854, 986.2898712926682, 995.4475266227313, 1007.1841808135433, 1027.445179603021, 1053.1101219469535, 9173.882983750273, 10352.378857268588, 10496.422696751599, 10560.049595536439, 10786.946065269623, 563932.0, 592072.0, 596213.0, 599356.0, 607009.0, 147634.26182869016, 148091.0, 150021.0, 151661.24169379083, 152621.0, 979.1090108001827, 985.1084768284713, 1110.5224753355974, 1142.3260276436943, 1186.6973921650097, 21030.263940762612, 22786.974586143995, 22811.31566053021, 23032.0, 23131.72775497364, 67526.98709519263, 70362.0, 70503.32383665675, 70828.0, 71238.28761712316, 66268.6686566564, 89631.0, 89865.0, 93202.53398732963, 93722.0, 11512.424378781196, 11549.307041577533, 11775.882241177822, 11778.0, 11781.293122412682, 14681.706365872837, 18021.369252076114, 18045.097745112955, 18514.629707406046, 18552.628947421246, 12904.548340808027, 14631.487897923522, 14644.41422343137, 14647.0, 14758.48345307909, 11338.659840204808, 12445.937057779822, 12646.0, 13372.732545348845, 13575.981801365135, 13784.931075344559, 14853.554393368217, 14890.553283401516, 14979.0, 15089.170095644846, 11650.592229271935, 11652.475638596454, 11690.941545292559, 11703.356315402734, 11879.821802672794, 14505.202213877916, 14637.414503419745, 14788.334524946187, 14902.85097149058, 15007.774883376977], "value": 11579.635436682085, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1240.4294024748615, 1425.950091746789, 1438.5971927860203, 1457.709926714857, 1463.626775172331, 13348.532801351943, 13922.373493192805, 13938.51215207468, 13956.2324072176, 13958.0, 13514.864851351507, 13995.930020349884, 14006.299685015734, 14014.369353379097, 14016.719665606683, 2789.8326100433987, 2822.20978126124, 2829.476546838831, 2833.9858300708465, 2839.985800070997, 2768.0, 2794.9720502795017, 2815.85920703965, 2824.166870773127, 2853.743163115319, 2754.6969833318317, 2788.2889863084883, 2793.7206279372103, 2797.5523916173415, 2801.719828017192, 2326.7440581536016, 2734.480448714738, 2768.0, 2771.1409463066516, 2771.6951135375093, 2418.8427752196226, 2632.197179860142, 2716.1987213771786, 2727.2500062482777, 2745.258780129354, 13469.393877275546, 13772.0, 13780.310984450738, 13785.379657915419, 13796.896248300112, 7538.962305188494, 7737.806554836118, 7762.883556746653, 7804.102528209215, 7814.570198639072, 4248.277792775205, 4618.976905115486, 4724.645651576111, 4934.580560652349, 4957.132501812204, 1226.9570864764967, 1402.2708191740237, 1411.9435222591085, 1419.6308959670457, 1432.0619993904008, 18971.24115035397, 19500.219991200334, 20625.0, 20716.378508644764, 20929.581408371898, 5932.703364831741, 6685.665716714145, 6856.760013399507, 6907.481938854606, 6972.337627925339, 14454.494092706705, 14590.927045364706, 14596.781048284285, 14610.561683149523, 14619.415223391157, 7248.130224373003, 7516.962415187889, 7542.358899493494, 7611.124720657195, 7646.923530764732, 6793.0, 6966.9651651741415, 7026.578405295699, 7052.224255331909, 7059.541129826544, 5203.19350500673, 5238.97380513095, 5306.46935306474, 5371.75827087782, 5376.354837419534, 1489.7690857917041, 1524.100780539487, 1524.6417091983376, 1537.538738378482, 1547.5589457004698, 745.2361329637057, 845.4969293270494, 852.228732996639, 853.2065179383109, 884.5930871798915, 6022.789202377896, 7409.184989651134, 7429.5542267464125, 7465.104187497535, 7474.626268686544, 659.4856012310405, 685.4208194075998, 710.8969199466152, 718.1489934427719, 720.3552820225868, 4278.187144442546, 4461.843835465743, 5275.57795376369, 5347.679139251657, 5594.86012849682, 17933.551661208574, 18486.0, 18498.260069597065, 18860.962647054545, 18862.24551017944, 16177.838221617632, 17512.0, 18022.90988545049, 18634.347797827282, 18811.90594047021, 8440.493570385797, 8592.656293748305, 9121.635134594542, 9603.567839447242, 9668.226541876633, 7356.0, 7959.602019899097, 7998.720044798404, 8073.15231900642, 8530.658773648984, 16703.16484175762, 16869.903456275304, 16873.831261687716, 17245.827541724422, 17257.309707611555, 15294.770578441556, 16640.364720322978, 17235.965842049518, 17811.821881781016, 17983.550411239823, 9858.408495490294, 10347.327423717434, 11025.779484410426, 11057.336559806437, 11137.331760094421, 12555.811662825248, 12596.811047834106, 12684.365781711062, 12688.619341419771, 12694.746105078031, 9897.703068907944, 10010.998900109931, 10113.949430252802, 10365.585376584851, 10480.842787358044, 9705.369151005161, 9768.36505627132, 10101.090901818872, 10194.796104077728, 10212.029857163558, 9252.53737313145, 9268.443893366419, 9302.02328755471, 9390.483523406278, 9424.764380890532, 6613.966930165318, 6675.4325882300545, 6786.185657721105, 6984.860302794017, 7041.929580704127, 6732.326767323424, 6899.650768066115, 7176.425885929218, 7221.428251310874, 7366.226546212571], "value": 6124.925058893354, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.87246517347035, 431.10115409371457, 444.2226104317443, 456.5138127893793, 465.2509459769772, 6011.639301641904, 6551.410373066429, 6601.966990165054, 6620.735170593182, 6675.232348279949, 6133.723982420791, 6374.968125159367, 6401.175664935495, 6411.230652321717, 6415.230172379309, 4501.77162304658, 4546.976930190714, 4784.521547845223, 4819.0, 4840.225563909775, 4784.330193772875, 4793.472718001017, 4804.327394164819, 4822.975885120587, 4828.17920953437, 4359.215341238576, 4371.683008005762, 4632.641335032108, 4758.095961767271, 4774.880627984294, 3969.4442778010957, 4011.1376054148354, 4340.283853164256, 4568.269076947706, 4734.55021772931, 4155.252054630166, 4158.2307273154565, 4177.227212965611, 4187.497500299984, 4200.97076216329, 5445.591580631429, 6215.56491045627, 6240.344763799781, 6255.374462553754, 6257.5619706620555, 5631.943680563183, 5650.208970744099, 5650.717464126818, 5673.546116310727, 5690.430956904318, 5482.738970036914, 6006.369331220203, 6833.4191593714595, 6920.238773734885, 6936.861262774767, 4768.785404656799, 4795.6163506919365, 4796.616270698336, 4799.472058073609, 4802.4957379475, 6601.438877695402, 7168.9246613007945, 7187.938814569751, 7315.963420182865, 7380.963095184544, 4044.4337792709043, 4230.598093181143, 4878.536539028786, 4885.8289959851945, 4889.3888263967, 4504.7611906725915, 4834.830780922651, 4973.154563724175, 4979.402471703346, 5001.574551252948, 5671.943280567224, 5673.687947162945, 5692.206954809222, 5693.800716974886, 5700.543956483471, 6347.238331400262, 6959.547629404073, 6968.512204145763, 6986.301369863075, 6996.965015174993, 1453.5827568120997, 1554.7356949318644, 1560.2978659603225, 1706.223668230952, 1730.2127531972922, 917.2936838634214, 952.9231967876256, 1062.458146345354, 1138.755167638945, 1147.8737338892715, 5715.85682863429, 6211.596246243979, 6346.810350429095, 6400.519961002943, 6434.742610295536, 5991.55063370249, 6747.224069232003, 7120.6439678015895, 7178.712851485987, 7182.856342873218, 5439.428859969726, 5446.564274858002, 6420.357964203636, 6451.29035806061, 6477.708503117371, 3407.812570308607, 3667.394879844796, 3733.5893051764283, 3753.8123093845197, 3756.436534519844, 6566.967165164144, 6945.583265004116, 7247.420206383476, 7265.818354541073, 7280.41756659466, 7259.963700181571, 7301.707931682673, 7316.0, 7325.963370183115, 7377.668004939684, 5701.890300588643, 6521.934780652227, 6677.332266773381, 6870.587764734232, 7256.9274307256255, 7092.432605391453, 7097.751578694927, 7099.0, 7127.786166415016, 7149.428045756223, 6966.133795743209, 6992.860142797013, 7003.649817509002, 7027.648617569203, 7051.717931282691, 6590.7693230736695, 6715.261321254756, 6884.965575172092, 7014.598431376509, 7190.640467976685, 4234.259004674232, 4798.288966733784, 4798.328234047166, 4801.68789028712, 4805.831795887128, 6296.307406185224, 7021.331893880293, 7080.575165490087, 7083.0, 7093.32613401726, 5940.524758019262, 6175.6912154393, 6345.7144428499905, 6842.076319696777, 6844.384005439534, 6327.082573026793, 6736.090627765189, 6812.216595091628, 6815.625140617313, 6834.419074378733, 6649.235337936199, 6666.300038495888, 6672.566283191577, 6675.833104172434, 6679.66211825857, 5377.865553361197, 5395.56835453155, 6567.540272180901, 6747.055412242192, 6813.4889883257965, 5824.242848429677, 6647.966760166169, 6651.800445986628, 6688.461653819746, 6698.492839111161], "value": 5008.1830496916755, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.1684230504139, 842.6039761312181, 852.7569642651841, 870.8911386076741, 874.1782724239215, 16881.493555193334, 17196.82803171968, 17389.13054347284, 17393.478195654152, 17422.651546969053, 13007.674808129777, 13372.665683357922, 13452.394642241099, 13541.59375218745, 13901.860981390184, 936.9016253293408, 945.3477100800443, 955.0401846144615, 957.8515330123842, 958.1233171647934, 976.4629453800417, 977.0669011094387, 981.0140808487461, 981.1071924548662, 983.9852402213972, 1014.9898501015006, 1015.6445244164557, 1016.0753714120152, 1017.735388798914, 1021.4280003198232, 1001.9949900250489, 1003.8243307421172, 1040.4381633917637, 1045.142982754142, 1045.3414348960182, 733.0726630812027, 750.0324581290129, 750.0961341583434, 751.9548827070394, 753.2693287511105, 17172.742408863876, 17284.30862765488, 17389.565260868454, 17498.387556435464, 17527.03601301928, 17287.049212293317, 17862.0, 18151.54621134469, 18162.909185454122, 18278.908605456887, 14645.487407940778, 15114.848851511455, 15164.24178791056, 15191.544253672408, 15194.620134496616, 3567.4113771227976, 3620.4207326827845, 3633.8728144514816, 3649.178934739689, 3651.580068292154, 114860.99999999917, 147829.0, 149618.0, 152367.0, 157193.0, 12618.81071783924, 13542.59372218835, 13717.519886803913, 13841.930790345983, 14044.578662640137, 23954.0, 25259.62110568343, 26780.330491737473, 26816.06143784958, 26852.59721104185, 8306.750797475965, 8347.040090389683, 8357.456765310235, 8401.747947561584, 8413.200745929127, 4108.198901214294, 4143.97928010364, 4188.455500784939, 4195.853145139906, 4199.748015119103, 4664.720116793003, 4726.763661816895, 4755.358026666425, 4756.1438940990265, 4764.618830493552, 852.4888176621116, 904.1230006893303, 906.8141031088611, 908.3414524469706, 911.7720569857536, 590.5157770628044, 591.7278052096084, 593.964362138273, 611.7002668692309, 635.3106879036297, 10994.670159895217, 11798.351090689923, 12145.878541214652, 12165.513379464723, 12359.0, 575.7351618255649, 590.5895402695171, 600.5225845452876, 607.3501353551658, 612.2836281550597, 5223.608229382811, 5958.851028724316, 6206.441420272197, 6331.303556608769, 6562.5406221564035, 142851.0, 144941.0, 152125.0, 154272.0, 156577.21711391598, 126235.0, 127748.0, 129235.35382323028, 130266.0, 136435.0, 74328.0, 80357.0, 111144.0, 112367.0, 114677.85322146838, 9712.368696034735, 10267.94866025665, 10515.106215971728, 12726.0, 12977.091603587658, 26944.461110778066, 36526.0, 40084.1983160341, 48316.033679326916, 48949.2657610129, 60840.39159608347, 61852.0, 62936.68531657312, 63856.68071659612, 65436.018459724095, 13744.518941837388, 17238.655226895644, 17246.22391992338, 17259.3959211427, 17328.306867725652, 15216.619584510476, 17931.0, 18323.54191145232, 18438.35465758692, 18552.81447185511, 16694.332226710794, 16898.66202675964, 16904.0, 16945.576360591083, 17482.125893705008, 16398.672026559165, 16666.083365414535, 16852.915735421735, 17116.65766684684, 17197.656046878743, 16248.025118492928, 16689.66620667604, 16738.4141555045, 16959.0, 16979.0, 11311.660650180507, 11408.201425900108, 11519.76960460803, 12151.574694885636, 12453.501859925502, 12299.815502767287, 13555.390007449885, 13601.591952241448, 14702.117872927658, 14723.852761472677], "value": 8671.565512728963, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1537.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 171.0, 123.0, 113.0, 163.0, 96.0, 157.0, 81.0, 132.0, 180.0], "value": 134.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 99.0, 169.0, 189.0, 96.0, 160.0, 136.0, 175.0, 161.0, 114.0], "value": 144.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 205.0, 199.0, 205.0, 197.0, 198.0, 196.0, 203.0, 207.0, 197.0], "value": 200.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 79.0, 79.0, 159.0, 56.0, 58.0, 59.0, 61.0, 126.0, 58.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 119.0, 68.0, 69.0, 71.0, 107.0, 70.0, 70.0, 115.0], "value": 82.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 91.0, 90.0, 91.0, 89.0, 89.0, 95.0, 90.0, 90.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 96.0, 97.0, 97.0, 98.0, 96.0, 97.0, 96.0, 97.0], "value": 96.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 89.0, 92.0, 93.0, 91.0, 91.0, 92.0, 89.0, 88.0], "value": 90.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 50.0, 49.0, 55.0, 79.0, 77.0, 56.0, 52.0, 52.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 58.0, 62.0, 61.0, 60.0, 57.0, 56.0, 54.0], "value": 57.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 77.0, 90.0, 99.0, 80.0, 79.0, 80.0, 82.0, 71.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 143.0, 145.0, 142.0, 134.0, 136.0, 139.0, 142.0, 136.0, 179.0], "value": 143.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 166.0, 204.0, 224.0, 169.0, 226.0, 209.0, 181.0, 223.0, 224.0], "value": 199.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 65.0, 66.0, 95.0, 93.0, 90.0, 92.0, 72.0], "value": 77.4, "unit": "score"}]}]} 15:41:27 INFO - Return code: 0 15:41:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:41:27 INFO - # TBPL SUCCESS # 15:41:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:41:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:41:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:41:27 INFO - 2017-06-15 15:41:27.566 screenresolution[1690:11227] starting screenresolution argv=screenresolution get 15:41:27 INFO - 2017-06-15 15:41:27.569 screenresolution[1690:11227] Display 0: 1600x1200x32@60 15:41:27 INFO - 2017-06-15 15:41:27.575 screenresolution[1691:11230] starting screenresolution argv=screenresolution list 15:41:27 INFO - Available Modes on Display 0 15:41:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:41:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:41:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:41:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:41:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:41:27 INFO - Intel Iris: 15:41:27 INFO - Chipset Model: Intel Iris 15:41:27 INFO - Type: GPU 15:41:27 INFO - Bus: Built-In 15:41:27 INFO - VRAM (Dynamic, Max): 1536 MB 15:41:27 INFO - Vendor: Intel (0x8086) 15:41:27 INFO - Device ID: 0x0a2e 15:41:27 INFO - Revision ID: 0x0009 15:41:27 INFO - Displays: 15:41:27 INFO - Display: 15:41:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:41:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:41:27 INFO - Display Serial Number: 1600x1200 60 15:41:27 INFO - Main Display: Yes 15:41:27 INFO - Mirror: Off 15:41:27 INFO - Online: Yes 15:41:27 INFO - Rotation: Supported 15:41:27 INFO - Return code: 0 15:41:27 INFO - Running post-action listener: _resource_record_post_action 15:41:27 INFO - [mozharness: 2017-06-15 22:41:27.810759Z] Finished run-tests step (success) 15:41:27 INFO - Running post-run listener: _resource_record_post_run 15:41:27 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 41305088; Write bytes: 558538752; Read time: 287; Write time: 1719 15:41:27 INFO - TinderboxPrint: CPU usage
53.1% 15:41:27 INFO - TinderboxPrint: I/O read bytes / time
41,305,088 / 287 15:41:27 INFO - TinderboxPrint: I/O write bytes / time
558,538,752 / 1,719 15:41:27 INFO - TinderboxPrint: CPU idle
2,353.9 (46.9%) 15:41:27 INFO - TinderboxPrint: CPU system
1,015.6 (20.2%) 15:41:27 INFO - TinderboxPrint: CPU user
1,652.2 (32.9%) 15:41:27 INFO - TinderboxPrint: Swap in / out
283,910,144 / 0 15:41:27 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 234127872; Write bytes: 228071424; Read time: 16856; Write time: 485 15:41:27 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:41:27 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 39285760; Write bytes: 312129536; Read time: 268; Write time: 1189 15:41:28 INFO - Running post-run listener: _upload_blobber_files 15:41:28 INFO - Blob upload gear active. 15:41:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:41:28 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 15:41:28 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'] 15:41:28 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 15:41:28 INFO - (blobuploader) - INFO - Open directory for files ... 15:41:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:41:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:41:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:41:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:41:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:41:30 INFO - (blobuploader) - INFO - Done attempting. 15:41:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:41:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:41:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:41:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:41:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:41:31 INFO - (blobuploader) - INFO - Done attempting. 15:41:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:41:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:41:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:41:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:41:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:41:32 INFO - (blobuploader) - INFO - Done attempting. 15:41:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:41:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:41:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:41:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:41:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:41:34 INFO - (blobuploader) - INFO - Done attempting. 15:41:34 INFO - (blobuploader) - INFO - Iteration through files over. 15:41:34 INFO - Return code: 0 15:41:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:41:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:41:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fba10acc84b7894a309431499d15146ba079d9e10028f2561250a563407c3d482703d08a02d128bbf482ef080b38496f2f9388675c7830042cce4acc3609952f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b8a500662272e551482724d56e963a940744e0a853d514082b05cd095efa42e171b1000c984386eb89e365ab629a2ae2cbdc105585246c8b07997712efbdd239", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/407b179c2d0cd6e7976e0193b8fdf52403c8c74d0397804babe37f78221c8dd128ff511c86c025b27935be13a9ffaf2dc5715e72a2957c47deba3b4dbdbb5223", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3abd4b87a80f49dc428c75e785aaf9de6ebfdbee25da46695d29070d7891d77babfbce0156981876e20b640121683bb9f06d598f6d85facf3128daa576b3b02"} 15:41:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:41:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:41:34 INFO - Contents: 15:41:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fba10acc84b7894a309431499d15146ba079d9e10028f2561250a563407c3d482703d08a02d128bbf482ef080b38496f2f9388675c7830042cce4acc3609952f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b8a500662272e551482724d56e963a940744e0a853d514082b05cd095efa42e171b1000c984386eb89e365ab629a2ae2cbdc105585246c8b07997712efbdd239", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/407b179c2d0cd6e7976e0193b8fdf52403c8c74d0397804babe37f78221c8dd128ff511c86c025b27935be13a9ffaf2dc5715e72a2957c47deba3b4dbdbb5223", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3abd4b87a80f49dc428c75e785aaf9de6ebfdbee25da46695d29070d7891d77babfbce0156981876e20b640121683bb9f06d598f6d85facf3128daa576b3b02"} 15:41:34 INFO - Running post-run listener: copy_logs_to_upload_dir 15:41:34 INFO - Copying logs to upload dir... 15:41:34 INFO - mkdir: /builds/slave/test/build/upload/logs 15:41:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.941516 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-15 15:41:34.485400) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-15 15:41:34.488740) ========= 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.Hu5JBvZJIt/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.cUlABhcNpv/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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/fba10acc84b7894a309431499d15146ba079d9e10028f2561250a563407c3d482703d08a02d128bbf482ef080b38496f2f9388675c7830042cce4acc3609952f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b8a500662272e551482724d56e963a940744e0a853d514082b05cd095efa42e171b1000c984386eb89e365ab629a2ae2cbdc105585246c8b07997712efbdd239", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/407b179c2d0cd6e7976e0193b8fdf52403c8c74d0397804babe37f78221c8dd128ff511c86c025b27935be13a9ffaf2dc5715e72a2957c47deba3b4dbdbb5223", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3abd4b87a80f49dc428c75e785aaf9de6ebfdbee25da46695d29070d7891d77babfbce0156981876e20b640121683bb9f06d598f6d85facf3128daa576b3b02"} build_url:https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011323 build_url: 'https://queue.taskcluster.net/v1/task/Q7bkuRLUTMG_RsimW-i07A/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/fba10acc84b7894a309431499d15146ba079d9e10028f2561250a563407c3d482703d08a02d128bbf482ef080b38496f2f9388675c7830042cce4acc3609952f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b8a500662272e551482724d56e963a940744e0a853d514082b05cd095efa42e171b1000c984386eb89e365ab629a2ae2cbdc105585246c8b07997712efbdd239", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/407b179c2d0cd6e7976e0193b8fdf52403c8c74d0397804babe37f78221c8dd128ff511c86c025b27935be13a9ffaf2dc5715e72a2957c47deba3b4dbdbb5223", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3abd4b87a80f49dc428c75e785aaf9de6ebfdbee25da46695d29070d7891d77babfbce0156981876e20b640121683bb9f06d598f6d85facf3128daa576b3b02"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-15 15:41:34.521426) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 15:41:34.521763) ========= 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.Hu5JBvZJIt/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.cUlABhcNpv/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.004878 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 15:41:34.570093) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-15 15:41:34.570413) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-15 15:41:34.570696) ========= ========= Total master_lag: 0.17 =========