builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0390 starttime: 1497986270.38 results: success (0) buildid: 20170620102757 builduid: dd3fc0233cd34b58a9bb0bbe51307a47 revision: f82ee19ca78db55bf66ae0de4cf9cbac987b67f8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-20 12:17:50.381380) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-20 12:17:50.381948) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-20 12:17:50.382301) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-20 12:17:50.408852) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 12:17:50.409294) ========= 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.Ez7hcIS3W7/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.JeGU6h04Ow/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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-20 12:17:50-- 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% 18.5M=0.001s 2017-06-20 12:17:50 (18.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130885 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 12:17:50.563868) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 12:17:50.564237) ========= 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.Ez7hcIS3W7/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.JeGU6h04Ow/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.026707 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 12:17:50.613831) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-20 12:17:50.614335) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f82ee19ca78db55bf66ae0de4cf9cbac987b67f8 --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 f82ee19ca78db55bf66ae0de4cf9cbac987b67f8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ez7hcIS3W7/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.JeGU6h04Ow/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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-20 12:17:50,694 truncating revision to first 12 chars 2017-06-20 12:17:50,694 Setting DEBUG logging. 2017-06-20 12:17:50,694 attempt 1/10 2017-06-20 12:17:50,695 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f82ee19ca78d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-20 12:17:52,859 unpacking tar archive at: mozilla-beta-f82ee19ca78d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.445358 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-20 12:17:53.081676) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-20 12:17:53.081998) ========= script_repo_revision: f82ee19ca78db55bf66ae0de4cf9cbac987b67f8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-20 12:17:53.082407) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-20 12:17:53.082671) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-20 12:17:53.101077) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-20 12:17:53.101451) ========= /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.Ez7hcIS3W7/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.JeGU6h04Ow/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 12:17:53 INFO - MultiFileLogger online at 20170620 12:17:53 in /builds/slave/test 12:17:53 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 12:17:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:17:53 INFO - {'append_to_log': False, 12:17:53 INFO - 'base_work_dir': '/builds/slave/test', 12:17:53 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:17:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:17:53 INFO - 'branch': 'Mozilla-Beta', 12:17:53 INFO - 'buildbot_json_path': 'buildprops.json', 12:17:53 INFO - 'config_files': ('talos/mac_config.py',), 12:17:53 INFO - 'default_actions': ('clobber', 12:17:53 INFO - 'read-buildbot-config', 12:17:53 INFO - 'download-and-extract', 12:17:53 INFO - 'populate-webroot', 12:17:53 INFO - 'create-virtualenv', 12:17:53 INFO - 'install', 12:17:53 INFO - 'setup-mitmproxy', 12:17:53 INFO - 'run-tests'), 12:17:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:17:53 INFO - 'download_minidump_stackwalk': True, 12:17:53 INFO - 'download_symbols': 'ondemand', 12:17:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:17:53 INFO - 'tooltool.py': '/tools/tooltool.py', 12:17:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:17:53 INFO - '/tools/misc-python/virtualenv.py')}, 12:17:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:17:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:17:53 INFO - 'gecko_profile': False, 12:17:53 INFO - 'gecko_profile_interval': 0, 12:17:53 INFO - 'installer_path': 'installer.exe', 12:17:53 INFO - 'log_level': 'info', 12:17:53 INFO - 'log_name': 'talos', 12:17:53 INFO - 'log_to_console': True, 12:17:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:17:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:17:53 INFO - 'opt_config_files': (), 12:17:53 INFO - 'pip_index': False, 12:17:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:17:53 INFO - 'cmd': ('bash', 12:17:53 INFO - '-c', 12:17:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:17:53 INFO - 'enabled': True, 12:17:53 INFO - 'halt_on_failure': False, 12:17:53 INFO - 'name': 'check_screen_resolution'},), 12:17:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:17:53 INFO - 'cmd': ('bash', 12:17:53 INFO - '-c', 12:17:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:17:53 INFO - 'enabled': True, 12:17:53 INFO - 'halt_on_failure': False, 12:17:53 INFO - 'name': 'check_screen_resolution'},), 12:17:53 INFO - 'run_cmd_checks_enabled': True, 12:17:53 INFO - 'suite': 'dromaeojs-e10s', 12:17:53 INFO - 'system_bits': '32', 12:17:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:17:53 INFO - 'title': 't-yosemite-r7-0390', 12:17:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:17:53 INFO - 'use_talos_json': True, 12:17:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:17:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:17:53 INFO - 'work_dir': 'build'} 12:17:53 INFO - [mozharness: 2017-06-20 19:17:53.291927Z] Running clobber step. 12:17:53 INFO - Running pre-action listener: _resource_record_pre_action 12:17:53 INFO - Running main action method: clobber 12:17:53 INFO - rmtree: /builds/slave/test/build 12:17:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:17:55 INFO - Running post-action listener: _resource_record_post_action 12:17:55 INFO - [mozharness: 2017-06-20 19:17:55.681049Z] Finished clobber step (success) 12:17:55 INFO - [mozharness: 2017-06-20 19:17:55.681179Z] Running read-buildbot-config step. 12:17:55 INFO - Running pre-action listener: _resource_record_pre_action 12:17:55 INFO - Running main action method: read_buildbot_config 12:17:55 INFO - Using buildbot properties: 12:17:55 INFO - { 12:17:55 INFO - "project": "", 12:17:55 INFO - "product": "firefox", 12:17:55 INFO - "script_repo_revision": "production", 12:17:55 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 12:17:55 INFO - "repository": "", 12:17:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 12:17:55 INFO - "buildid": "20170620102757", 12:17:55 INFO - "pgo_build": "False", 12:17:55 INFO - "basedir": "/builds/slave/test", 12:17:55 INFO - "buildnumber": 8, 12:17:55 INFO - "slavename": "t-yosemite-r7-0390", 12:17:55 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 12:17:55 INFO - "platform": "yosemite_r7_devedition", 12:17:55 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 12:17:55 INFO - "branch": "mozilla-beta", 12:17:55 INFO - "repo_path": "releases/mozilla-beta", 12:17:55 INFO - "revision": "f82ee19ca78db55bf66ae0de4cf9cbac987b67f8", 12:17:55 INFO - "stage_platform": "macosx64-devedition", 12:17:55 INFO - "builduid": "dd3fc0233cd34b58a9bb0bbe51307a47", 12:17:55 INFO - "slavebuilddir": "test" 12:17:55 INFO - } 12:17:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 12:17:55 INFO - Running post-action listener: _resource_record_post_action 12:17:55 INFO - [mozharness: 2017-06-20 19:17:55.684644Z] Finished read-buildbot-config step (success) 12:17:55 INFO - [mozharness: 2017-06-20 19:17:55.684775Z] Running download-and-extract step. 12:17:55 INFO - Running pre-action listener: _resource_record_pre_action 12:17:55 INFO - Running main action method: download_and_extract 12:17:55 INFO - mkdir: /builds/slave/test/build/tests 12:17:55 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')]} 12:17:55 INFO - trying https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 12:17:55 INFO - Downloading https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/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 12:17:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/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 12:17:56 INFO - Downloaded 1290 bytes. 12:17:56 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 12:17:56 INFO - Using the following test package requirements: 12:17:56 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:17:56 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 12:17:56 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 12:17:56 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:17:56 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 12:17:56 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:17:56 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 12:17:56 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:17:56 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:17:56 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 12:17:56 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 12:17:56 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:17:56 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 12:17:56 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:17:56 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 12:17:56 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:17:56 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 12:17:56 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:17:56 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 12:17:56 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 12:17:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 12:17:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 12:17:56 INFO - Fetch https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 12:17:58 INFO - Content-Length response header: 59545190 12:17:58 INFO - Bytes received: 59545190 12:18:16 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 12:18:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 12:18:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 12:18:16 INFO - Fetch https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 12:18:18 INFO - Content-Length response header: 59545190 12:18:18 INFO - Bytes received: 59545190 12:18:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 12:18:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 12:18:35 INFO - Fetch https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 12:18:36 INFO - Content-Length response header: 14454470 12:18:36 INFO - Bytes received: 14454470 12:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:37 INFO - trying https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 12:18:37 INFO - Downloading https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 12:18:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/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 12:18:39 INFO - Downloaded 66850832 bytes. 12:18:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 12:18:39 INFO - mkdir: /builds/slave/test/properties 12:18:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:18:39 INFO - Writing to file /builds/slave/test/properties/build_url 12:18:39 INFO - Contents: 12:18:39 INFO - build_url:https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 12:18:39 INFO - Running post-action listener: _resource_record_post_action 12:18:39 INFO - Running post-action listener: set_extra_try_arguments 12:18:39 INFO - [mozharness: 2017-06-20 19:18:39.570570Z] Finished download-and-extract step (success) 12:18:39 INFO - [mozharness: 2017-06-20 19:18:39.570719Z] Running populate-webroot step. 12:18:39 INFO - Running pre-action listener: _resource_record_pre_action 12:18:39 INFO - Running main action method: populate_webroot 12:18:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:18:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:18:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:18:39 INFO - '--tppagecycles', 12:18:39 INFO - '7'], 12:18:39 INFO - 'tests': ['tsvgm']}}, 12:18:39 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:18:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:18:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:18:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:18:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:18:39 INFO - 'talos_options': ['--disable-e10s'], 12:18:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:18:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:18:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:18:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:18:39 INFO - 'talos_options': ['--disable-e10s'], 12:18:39 INFO - 'tests': ['damp', 'tps']}, 12:18:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:18:39 INFO - 'tests': ['damp', 'tps']}, 12:18:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:18:39 INFO - 'tests': ['dromaeo_dom']}, 12:18:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:18:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:18:39 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:18:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:18:39 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:18:39 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:18:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:18:39 INFO - 'tests': ['a11yr', 12:18:39 INFO - 'ts_paint', 12:18:39 INFO - 'tpaint', 12:18:39 INFO - 'sessionrestore', 12:18:39 INFO - 'sessionrestore_no_auto_restore', 12:18:39 INFO - 'tabpaint']}, 12:18:39 INFO - 'other-e10s': {'tests': ['a11yr', 12:18:39 INFO - 'ts_paint', 12:18:39 INFO - 'tpaint', 12:18:39 INFO - 'sessionrestore', 12:18:39 INFO - 'sessionrestore_no_auto_restore', 12:18:39 INFO - 'tabpaint']}, 12:18:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:18:39 INFO - 'ts_paint', 12:18:39 INFO - 'tpaint', 12:18:39 INFO - 'sessionrestore', 12:18:39 INFO - 'sessionrestore_no_auto_restore', 12:18:39 INFO - 'tabpaint']}, 12:18:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:18:39 INFO - 'ts_paint', 12:18:39 INFO - 'tpaint', 12:18:39 INFO - 'sessionrestore', 12:18:39 INFO - 'sessionrestore_no_auto_restore', 12:18:39 INFO - 'tabpaint']}, 12:18:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:18:39 INFO - 'tests': ['a11yr', 12:18:39 INFO - 'ts_paint', 12:18:39 INFO - 'tpaint', 12:18:39 INFO - 'sessionrestore', 12:18:39 INFO - 'sessionrestore_no_auto_restore', 12:18:39 INFO - 'tabpaint']}, 12:18:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:18:39 INFO - 'tests': ['a11yr', 12:18:39 INFO - 'ts_paint', 12:18:39 INFO - 'tpaint', 12:18:39 INFO - 'sessionrestore', 12:18:39 INFO - 'sessionrestore_no_auto_restore', 12:18:39 INFO - 'tabpaint']}, 12:18:39 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:18:39 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:18:39 INFO - 'bloom_basic_ref']}, 12:18:39 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:18:39 INFO - 'talos_options': ['--mitmproxy', 12:18:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:18:39 INFO - '--firstNonBlankPaint'], 12:18:39 INFO - 'tests': ['quantum_pageload_google', 12:18:39 INFO - 'quantum_pageload_youtube', 12:18:39 INFO - 'quantum_pageload_amazon', 12:18:39 INFO - 'quantum_pageload_facebook']}, 12:18:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:18:39 INFO - 'tests': ['tsvgx', 12:18:39 INFO - 'tsvgr_opacity', 12:18:39 INFO - 'tart', 12:18:39 INFO - 'tscrollx', 12:18:39 INFO - 'cart', 12:18:39 INFO - 'tsvg_static']}, 12:18:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:18:39 INFO - 'tsvgr_opacity', 12:18:39 INFO - 'tart', 12:18:39 INFO - 'tscrollx', 12:18:39 INFO - 'cart', 12:18:39 INFO - 'tsvg_static']}, 12:18:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:18:39 INFO - 'talos_options': ['--disable-e10s'], 12:18:39 INFO - 'tests': ['tp5o']}, 12:18:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:18:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:18:39 INFO - 'talos_options': ['--disable-e10s', 12:18:39 INFO - '--xperf_path', 12:18:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:18:39 INFO - 'tests': ['tp5n']}, 12:18:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:18:39 INFO - 'talos_options': ['--xperf_path', 12:18:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:18:39 INFO - 'tests': ['tp5n']}}, 12:18:39 INFO - 'talos.zip': {'path': '', 12:18:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:18:39 INFO - Running post-action listener: _resource_record_post_action 12:18:39 INFO - [mozharness: 2017-06-20 19:18:39.591445Z] Finished populate-webroot step (success) 12:18:39 INFO - [mozharness: 2017-06-20 19:18:39.591546Z] Running create-virtualenv step. 12:18:39 INFO - Running pre-action listener: _resource_record_pre_action 12:18:39 INFO - Running main action method: create_virtualenv 12:18:39 INFO - Creating virtualenv /builds/slave/test/build/venv 12:18:39 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 12:18:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:18:39 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:18:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:18:39 INFO - Using real prefix '/tools/python27' 12:18:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:18:40 INFO - Installing distribute.............................................................................................................................................................................................done. 12:18:43 INFO - Installing pip.................done. 12:18:43 INFO - Return code: 0 12:18:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:18:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:18:43 INFO - Reading from file tmpfile_stdout 12:18:43 INFO - Output received: 12:18:43 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) 12:18:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107b09c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3734cbf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10785fd30>, '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 0x107829cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107850be8>, '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.Ez7hcIS3W7/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.JeGU6h04Ow/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 12:18:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:18:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:18:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ez7hcIS3W7/Render', 12:18:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:43 INFO - 'HOME': '/Users/cltbld', 12:18:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:43 INFO - 'LOGNAME': 'cltbld', 12:18:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:43 INFO - 'MOZ_NO_REMOTE': '1', 12:18:43 INFO - 'NO_EM_RESTART': '1', 12:18:43 INFO - 'PAGER': '/bin/cat', 12:18:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:43 INFO - 'PWD': '/builds/slave/test', 12:18:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:43 INFO - 'SHELL': '/bin/bash', 12:18:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeGU6h04Ow/Listeners', 12:18:43 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 12:18:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:43 INFO - 'USER': 'cltbld', 12:18:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:43 INFO - 'XPC_FLAGS': '0x0', 12:18:43 INFO - 'XPC_SERVICE_NAME': '0', 12:18:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:43 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 12:18:43 INFO - Cleaning up... 12:18:43 INFO - Return code: 0 12:18:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107b09c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3734cbf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10785fd30>, '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 0x107829cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107850be8>, '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.Ez7hcIS3W7/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.JeGU6h04Ow/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 12:18:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:18:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:18:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ez7hcIS3W7/Render', 12:18:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:43 INFO - 'HOME': '/Users/cltbld', 12:18:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:43 INFO - 'LOGNAME': 'cltbld', 12:18:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:43 INFO - 'MOZ_NO_REMOTE': '1', 12:18:43 INFO - 'NO_EM_RESTART': '1', 12:18:43 INFO - 'PAGER': '/bin/cat', 12:18:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:43 INFO - 'PWD': '/builds/slave/test', 12:18:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:43 INFO - 'SHELL': '/bin/bash', 12:18:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeGU6h04Ow/Listeners', 12:18:43 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 12:18:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:43 INFO - 'USER': 'cltbld', 12:18:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:43 INFO - 'XPC_FLAGS': '0x0', 12:18:43 INFO - 'XPC_SERVICE_NAME': '0', 12:18:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:44 INFO - Downloading/unpacking psutil>=3.1.1 12:18:44 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 12:18:44 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 12:18:44 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 12:18:44 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 12:18:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:18:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:18:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:18:45 INFO - Installing collected packages: psutil 12:18:45 INFO - Running setup.py install for psutil 12:18:46 INFO - building 'psutil._psutil_osx' extension 12:18:46 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 12:18:46 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 12:18:46 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 12:18:46 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 12:18:46 INFO - building 'psutil._psutil_posix' extension 12:18:46 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 12:18:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:18:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:18:46 INFO - ^ 12:18:46 INFO - 1 warning generated. 12:18:46 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 12:18:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:18:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:18:47 INFO - Successfully installed psutil 12:18:47 INFO - Cleaning up... 12:18:47 INFO - Return code: 0 12:18:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:18:47 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')]} 12:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:47 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')]} 12:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:47 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 0x107b09c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3734cbf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10785fd30>, '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 0x107829cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107850be8>, '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.Ez7hcIS3W7/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.JeGU6h04Ow/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 12:18:47 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 12:18:47 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 12:18:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ez7hcIS3W7/Render', 12:18:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:47 INFO - 'HOME': '/Users/cltbld', 12:18:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:47 INFO - 'LOGNAME': 'cltbld', 12:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:47 INFO - 'MOZ_NO_REMOTE': '1', 12:18:47 INFO - 'NO_EM_RESTART': '1', 12:18:47 INFO - 'PAGER': '/bin/cat', 12:18:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:47 INFO - 'PWD': '/builds/slave/test', 12:18:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:47 INFO - 'SHELL': '/bin/bash', 12:18:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeGU6h04Ow/Listeners', 12:18:47 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 12:18:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:47 INFO - 'USER': 'cltbld', 12:18:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:47 INFO - 'XPC_FLAGS': '0x0', 12:18:47 INFO - 'XPC_SERVICE_NAME': '0', 12:18:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:18:47 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 12:18:47 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 12:18:47 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 12:18:47 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 12:18:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:18:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:18:49 INFO - Installing collected packages: mozsystemmonitor 12:18:49 INFO - Running setup.py install for mozsystemmonitor 12:18:49 INFO - Successfully installed mozsystemmonitor 12:18:49 INFO - Cleaning up... 12:18:49 INFO - Return code: 0 12:18:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:18:49 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')]} 12:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:49 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')]} 12:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:49 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 0x107b09c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3734cbf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10785fd30>, '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 0x107829cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107850be8>, '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.Ez7hcIS3W7/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.JeGU6h04Ow/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 12:18:49 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 12:18:49 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 12:18:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ez7hcIS3W7/Render', 12:18:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:49 INFO - 'HOME': '/Users/cltbld', 12:18:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:49 INFO - 'LOGNAME': 'cltbld', 12:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:49 INFO - 'MOZ_NO_REMOTE': '1', 12:18:49 INFO - 'NO_EM_RESTART': '1', 12:18:49 INFO - 'PAGER': '/bin/cat', 12:18:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:49 INFO - 'PWD': '/builds/slave/test', 12:18:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:49 INFO - 'SHELL': '/bin/bash', 12:18:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeGU6h04Ow/Listeners', 12:18:49 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 12:18:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:49 INFO - 'USER': 'cltbld', 12:18:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:49 INFO - 'XPC_FLAGS': '0x0', 12:18:49 INFO - 'XPC_SERVICE_NAME': '0', 12:18:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:50 INFO - Downloading/unpacking jsonschema==2.5.1 12:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:18:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:18:51 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 12:18:51 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 12:18:51 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 12:18:51 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 12:18:51 INFO - Downloading functools32-3.2.3-2.tar.gz 12:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:18:51 INFO - warning: no files found matching '*.txt' 12:18:51 INFO - no previously-included directories found matching 'build' 12:18:51 INFO - no previously-included directories found matching 'dist' 12:18:51 INFO - no previously-included directories found matching '.git*' 12:18:51 INFO - Installing collected packages: jsonschema, functools32 12:18:52 INFO - Running setup.py install for functools32 12:18:52 INFO - warning: no files found matching '*.txt' 12:18:52 INFO - no previously-included directories found matching 'build' 12:18:52 INFO - no previously-included directories found matching 'dist' 12:18:52 INFO - no previously-included directories found matching '.git*' 12:18:52 INFO - Successfully installed jsonschema functools32 12:18:52 INFO - Cleaning up... 12:18:52 INFO - Return code: 0 12:18:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:18:52 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')]} 12:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:52 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')]} 12:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:52 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 0x107b09c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3734cbf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10785fd30>, '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 0x107829cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107850be8>, '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.Ez7hcIS3W7/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.JeGU6h04Ow/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 12:18:52 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 12:18:52 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 12:18:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ez7hcIS3W7/Render', 12:18:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:52 INFO - 'HOME': '/Users/cltbld', 12:18:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:52 INFO - 'LOGNAME': 'cltbld', 12:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:52 INFO - 'MOZ_NO_REMOTE': '1', 12:18:52 INFO - 'NO_EM_RESTART': '1', 12:18:52 INFO - 'PAGER': '/bin/cat', 12:18:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:52 INFO - 'PWD': '/builds/slave/test', 12:18:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:52 INFO - 'SHELL': '/bin/bash', 12:18:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeGU6h04Ow/Listeners', 12:18:52 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 12:18:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:52 INFO - 'USER': 'cltbld', 12:18:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:52 INFO - 'XPC_FLAGS': '0x0', 12:18:52 INFO - 'XPC_SERVICE_NAME': '0', 12:18:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:18:52 INFO - Cleaning up... 12:18:52 INFO - Return code: 0 12:18:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:18:52 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')]} 12:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:52 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')]} 12:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:52 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 0x107b09c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3734cbf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10785fd30>, '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 0x107829cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107850be8>, '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.Ez7hcIS3W7/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.JeGU6h04Ow/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 12:18:52 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 12:18:52 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 12:18:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ez7hcIS3W7/Render', 12:18:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:52 INFO - 'HOME': '/Users/cltbld', 12:18:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:52 INFO - 'LOGNAME': 'cltbld', 12:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:52 INFO - 'MOZ_NO_REMOTE': '1', 12:18:52 INFO - 'NO_EM_RESTART': '1', 12:18:52 INFO - 'PAGER': '/bin/cat', 12:18:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:52 INFO - 'PWD': '/builds/slave/test', 12:18:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:52 INFO - 'SHELL': '/bin/bash', 12:18:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeGU6h04Ow/Listeners', 12:18:52 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 12:18:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:52 INFO - 'USER': 'cltbld', 12:18:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:52 INFO - 'XPC_FLAGS': '0x0', 12:18:52 INFO - 'XPC_SERVICE_NAME': '0', 12:18:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:52 INFO - Downloading/unpacking blobuploader==1.2.4 12:18:52 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 12:18:52 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 12:18:52 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 12:18:52 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 12:18:54 INFO - Downloading blobuploader-1.2.4.tar.gz 12:18:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:18:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:18:54 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 12:18:54 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 12:18:54 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 12:18:54 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 12:18:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:18:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:18:54 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 12:18:54 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 12:18:54 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 12:18:54 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 12:18:54 INFO - Downloading docopt-0.6.1.tar.gz 12:18:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:18:55 INFO - Installing collected packages: blobuploader, requests, docopt 12:18:55 INFO - Running setup.py install for blobuploader 12:18:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:18:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:18:55 INFO - Running setup.py install for requests 12:18:55 INFO - Running setup.py install for docopt 12:18:55 INFO - Successfully installed blobuploader requests docopt 12:18:55 INFO - Cleaning up... 12:18:55 INFO - Return code: 0 12:18:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:18:55 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')]} 12:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:55 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')]} 12:18:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:55 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 0x107b09c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3734cbf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10785fd30>, '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 0x107829cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107850be8>, '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.Ez7hcIS3W7/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.JeGU6h04Ow/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 12:18:55 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 12:18:55 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 12:18:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ez7hcIS3W7/Render', 12:18:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:55 INFO - 'HOME': '/Users/cltbld', 12:18:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:55 INFO - 'LOGNAME': 'cltbld', 12:18:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:55 INFO - 'MOZ_NO_REMOTE': '1', 12:18:55 INFO - 'NO_EM_RESTART': '1', 12:18:55 INFO - 'PAGER': '/bin/cat', 12:18:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:55 INFO - 'PWD': '/builds/slave/test', 12:18:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:55 INFO - 'SHELL': '/bin/bash', 12:18:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeGU6h04Ow/Listeners', 12:18:55 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 12:18:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:55 INFO - 'USER': 'cltbld', 12:18:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:55 INFO - 'XPC_FLAGS': '0x0', 12:18:55 INFO - 'XPC_SERVICE_NAME': '0', 12:18:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:18:56 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-5WSB3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:18:56 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-rfKZpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:18:56 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-AJayY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:18:56 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-1ZPp__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:18:56 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-HgC6tW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:18:56 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-8E8tbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:18:56 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-zPtk3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:18:56 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-8uKQNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:18:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-QNUqga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:18:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-ErVJSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:18:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-WoHINw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:18:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-qEoijH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:18:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-vnXQhy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:18:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-MaB4nx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-EIAzvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:18:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-ZgiiEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:18:57 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-TyS14X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:18:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:18:58 INFO - Running setup.py install for manifestparser 12:18:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:18:58 INFO - Running setup.py install for mozcrash 12:18:58 INFO - Running setup.py install for mozdebug 12:18:58 INFO - Running setup.py install for mozdevice 12:18:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:18:58 INFO - Running setup.py install for mozfile 12:18:58 INFO - Running setup.py install for mozhttpd 12:18:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:18:58 INFO - Running setup.py install for mozinfo 12:18:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:18:59 INFO - Running setup.py install for mozInstall 12:18:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:18:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:18:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:18:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:18:59 INFO - Running setup.py install for mozleak 12:18:59 INFO - Running setup.py install for mozlog 12:18:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:18:59 INFO - Running setup.py install for moznetwork 12:18:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:18:59 INFO - Running setup.py install for mozprocess 12:18:59 INFO - Running setup.py install for mozprofile 12:18:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:18:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:18:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:18:59 INFO - Running setup.py install for mozrunner 12:19:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:19:00 INFO - Running setup.py install for mozscreenshot 12:19:00 INFO - Running setup.py install for moztest 12:19:00 INFO - Running setup.py install for mozversion 12:19:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:19:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:19:00 INFO - Cleaning up... 12:19:00 INFO - Return code: 0 12:19:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:19:00 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')]} 12:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:00 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')]} 12:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:00 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 0x107b09c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3734cbf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10785fd30>, '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 0x107829cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107850be8>, '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.Ez7hcIS3W7/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.JeGU6h04Ow/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 12:19:00 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 12:19:00 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 12:19:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ez7hcIS3W7/Render', 12:19:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:00 INFO - 'HOME': '/Users/cltbld', 12:19:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:00 INFO - 'LOGNAME': 'cltbld', 12:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:00 INFO - 'MOZ_NO_REMOTE': '1', 12:19:00 INFO - 'NO_EM_RESTART': '1', 12:19:00 INFO - 'PAGER': '/bin/cat', 12:19:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:00 INFO - 'PWD': '/builds/slave/test', 12:19:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:00 INFO - 'SHELL': '/bin/bash', 12:19:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeGU6h04Ow/Listeners', 12:19:00 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 12:19:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:00 INFO - 'USER': 'cltbld', 12:19:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:00 INFO - 'XPC_FLAGS': '0x0', 12:19:00 INFO - 'XPC_SERVICE_NAME': '0', 12:19:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:19:00 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-GyOMnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:19:01 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)) 12:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:19:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-ddjcUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:19:01 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)) 12:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:19:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-nGRJBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:19:01 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)) 12:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:19:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-wPpMnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:19:01 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)) 12:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:19:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-VVcrl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:19:01 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)) 12:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:19:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-oxjpls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:19:01 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)) 12:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:19:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-9yFDmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:19:01 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)) 12:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:19:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-BGBmyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:19:01 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)) 12:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:19:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-GZwyHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:19:01 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)) 12:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:19:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-_BNjtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:19:01 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)) 12:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:19:01 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-siWnWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:19:02 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)) 12:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:19:02 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-1Wd2AN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:19:02 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)) 12:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:19:02 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-3jymJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:19:02 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)) 12:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:19:02 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-9aDWLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:19:02 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)) 12:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:02 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-EcWPth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:02 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)) 12:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:19:02 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-j0EFTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:19:02 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)) 12:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:19:02 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-LeHxFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:19:02 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)) 12:19:02 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)) 12:19:02 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)) 12:19:02 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)) 12:19:02 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)) 12:19:02 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)) 12:19:02 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)) 12:19:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:19:02 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 12:19:02 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 12:19:02 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 12:19:02 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 12:19:04 INFO - Downloading blessings-1.6.tar.gz 12:19:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:19:04 INFO - Installing collected packages: blessings 12:19:04 INFO - Running setup.py install for blessings 12:19:04 INFO - Successfully installed blessings 12:19:04 INFO - Cleaning up... 12:19:04 INFO - Return code: 0 12:19:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:19:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:19:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:19:04 INFO - Reading from file tmpfile_stdout 12:19:04 INFO - Current package versions: 12:19:04 INFO - blessings == 1.6 12:19:04 INFO - blobuploader == 1.2.4 12:19:04 INFO - docopt == 0.6.1 12:19:04 INFO - functools32 == 3.2.3-2 12:19:04 INFO - jsonschema == 2.5.1 12:19:04 INFO - manifestparser == 1.1 12:19:04 INFO - mozInstall == 1.13 12:19:04 INFO - mozcrash == 1.0 12:19:04 INFO - mozdebug == 0.1 12:19:04 INFO - mozdevice == 0.51 12:19:04 INFO - mozfile == 1.2 12:19:04 INFO - mozhttpd == 0.7 12:19:04 INFO - mozinfo == 0.10 12:19:04 INFO - mozleak == 0.1 12:19:04 INFO - mozlog == 3.4 12:19:04 INFO - moznetwork == 0.27 12:19:04 INFO - mozprocess == 0.25 12:19:04 INFO - mozprofile == 0.28 12:19:04 INFO - mozrunner == 6.13 12:19:04 INFO - mozscreenshot == 0.1 12:19:04 INFO - mozsystemmonitor == 0.3 12:19:04 INFO - moztest == 0.8 12:19:04 INFO - mozversion == 1.4 12:19:04 INFO - psutil == 3.1.1 12:19:04 INFO - requests == 1.2.3 12:19:04 INFO - wsgiref == 0.1.2 12:19:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:19:04 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')]} 12:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:04 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')]} 12:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:04 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 0x107b09c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3734cbf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10785fd30>, '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 0x107829cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107850be8>, '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.Ez7hcIS3W7/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.JeGU6h04Ow/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 12:19:04 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 12:19:04 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 12:19:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ez7hcIS3W7/Render', 12:19:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:04 INFO - 'HOME': '/Users/cltbld', 12:19:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:04 INFO - 'LOGNAME': 'cltbld', 12:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:04 INFO - 'MOZ_NO_REMOTE': '1', 12:19:04 INFO - 'NO_EM_RESTART': '1', 12:19:04 INFO - 'PAGER': '/bin/cat', 12:19:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:04 INFO - 'PWD': '/builds/slave/test', 12:19:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:04 INFO - 'SHELL': '/bin/bash', 12:19:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeGU6h04Ow/Listeners', 12:19:04 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 12:19:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:04 INFO - 'USER': 'cltbld', 12:19:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:04 INFO - 'XPC_FLAGS': '0x0', 12:19:04 INFO - 'XPC_SERVICE_NAME': '0', 12:19:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:05 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)) 12:19:05 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)) 12:19:05 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)) 12:19:05 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)) 12:19:05 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)) 12:19:05 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)) 12:19:05 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)) 12:19:05 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)) 12:19:05 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)) 12:19:05 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:19:05 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 12:19:05 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 12:19:05 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 12:19:05 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 12:19:06 INFO - Downloading marionette_driver-1.4.0.tar.gz 12:19:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 12:19:06 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)) 12:19:06 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)) 12:19:06 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)) 12:19:06 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)) 12:19:06 INFO - Installing collected packages: marionette-driver 12:19:06 INFO - Running setup.py install for marionette-driver 12:19:06 INFO - Successfully installed marionette-driver 12:19:06 INFO - Cleaning up... 12:19:06 INFO - Return code: 0 12:19:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:06 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 0x107b09c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b0d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3734cbf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10785fd30>, '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 0x107829cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107850be8>, '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.Ez7hcIS3W7/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.JeGU6h04Ow/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 12:19:06 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 12:19:06 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 12:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ez7hcIS3W7/Render', 12:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:06 INFO - 'HOME': '/Users/cltbld', 12:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:06 INFO - 'LOGNAME': 'cltbld', 12:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:06 INFO - 'MOZ_NO_REMOTE': '1', 12:19:06 INFO - 'NO_EM_RESTART': '1', 12:19:06 INFO - 'PAGER': '/bin/cat', 12:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:06 INFO - 'PWD': '/builds/slave/test', 12:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:06 INFO - 'SHELL': '/bin/bash', 12:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeGU6h04Ow/Listeners', 12:19:06 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 12:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:06 INFO - 'USER': 'cltbld', 12:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:06 INFO - 'XPC_FLAGS': '0x0', 12:19:06 INFO - 'XPC_SERVICE_NAME': '0', 12:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:19:07 INFO - Cleaning up... 12:19:07 INFO - Return code: 0 12:19:07 INFO - Running post-action listener: _resource_record_post_action 12:19:07 INFO - Running post-action listener: _start_resource_monitoring 12:19:07 INFO - Starting resource monitoring. 12:19:07 INFO - [mozharness: 2017-06-20 19:19:07.064999Z] Finished create-virtualenv step (success) 12:19:07 INFO - [mozharness: 2017-06-20 19:19:07.066012Z] Running install step. 12:19:07 INFO - Running pre-action listener: _resource_record_pre_action 12:19:07 INFO - Running main action method: install 12:19:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:19:07 INFO - Reading from file tmpfile_stdout 12:19:07 INFO - Detecting whether we're running mozinstall >=1.0... 12:19:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:19:07 INFO - Reading from file tmpfile_stdout 12:19:07 INFO - Output received: 12:19:07 INFO - Usage: mozinstall [options] installer 12:19:07 INFO - Options: 12:19:07 INFO - -h, --help show this help message and exit 12:19:07 INFO - -d DEST, --destination=DEST 12:19:07 INFO - Directory to install application into. [default: 12:19:07 INFO - "/builds/slave/test"] 12:19:07 INFO - --app=APP Application being installed. [default: firefox] 12:19:07 INFO - mkdir: /builds/slave/test/build/application 12:19:07 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'] 12:19:07 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 12:19:28 INFO - Reading from file tmpfile_stdout 12:19:28 INFO - Output received: 12:19:28 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:19:28 INFO - Running post-action listener: _resource_record_post_action 12:19:28 INFO - [mozharness: 2017-06-20 19:19:28.369848Z] Finished install step (success) 12:19:28 INFO - [mozharness: 2017-06-20 19:19:28.369987Z] Running setup-mitmproxy step. 12:19:28 INFO - Running pre-action listener: _resource_record_pre_action 12:19:28 INFO - Running main action method: setup_mitmproxy 12:19:28 INFO - Skipping: mitmproxy is not required 12:19:28 INFO - Running post-action listener: _resource_record_post_action 12:19:28 INFO - [mozharness: 2017-06-20 19:19:28.370631Z] Finished setup-mitmproxy step (success) 12:19:28 INFO - [mozharness: 2017-06-20 19:19:28.370749Z] Running run-tests step. 12:19:28 INFO - Running pre-action listener: _resource_record_pre_action 12:19:28 INFO - Running main action method: run_tests 12:19:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:19:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:19:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:19:28 INFO - 2017-06-20 12:19:28.385 screenresolution[1639:7507] starting screenresolution argv=screenresolution get 12:19:28 INFO - 2017-06-20 12:19:28.388 screenresolution[1639:7507] Display 0: 1600x1200x32@60 12:19:28 INFO - 2017-06-20 12:19:28.394 screenresolution[1640:7509] starting screenresolution argv=screenresolution list 12:19:28 INFO - Available Modes on Display 0 12:19:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:19:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:19:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:19:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:19:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:19:28 INFO - Intel Iris: 12:19:28 INFO - Chipset Model: Intel Iris 12:19:28 INFO - Type: GPU 12:19:28 INFO - Bus: Built-In 12:19:28 INFO - VRAM (Dynamic, Max): 1536 MB 12:19:28 INFO - Vendor: Intel (0x8086) 12:19:28 INFO - Device ID: 0x0a2e 12:19:28 INFO - Revision ID: 0x0009 12:19:28 INFO - Displays: 12:19:28 INFO - Display: 12:19:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:19:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:19:28 INFO - Display Serial Number: 1600x1200 60 12:19:28 INFO - Main Display: Yes 12:19:28 INFO - Mirror: Off 12:19:28 INFO - Online: Yes 12:19:28 INFO - Rotation: Supported 12:19:28 INFO - Return code: 0 12:19:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:19:28 INFO - Python 2.7.3 12:19:28 INFO - Return code: 0 12:19:28 INFO - grabbing minidump binary from tooltool 12:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:28 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 0x10785fd30>, '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 0x107829cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107850be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:19:28 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 12:19:28 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 12:19:28 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 12:19:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:19:28 INFO - Return code: 0 12:19:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:19:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:19:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:19:28 INFO - ENV: RUST_BACKTRACE is now 1 12:19:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:19:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:19:28 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/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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 12:19:28 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/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0390 --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 12:19:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ez7hcIS3W7/Render', 12:19:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:28 INFO - 'HOME': '/Users/cltbld', 12:19:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:28 INFO - 'LOGNAME': 'cltbld', 12:19:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:19:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:28 INFO - 'MOZ_NO_REMOTE': '1', 12:19:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:19:28 INFO - 'NO_EM_RESTART': '1', 12:19:28 INFO - 'PAGER': '/bin/cat', 12:19:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:28 INFO - 'PWD': '/builds/slave/test', 12:19:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:19:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:28 INFO - 'RUST_BACKTRACE': '1', 12:19:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:19:28 INFO - 'SHELL': '/bin/bash', 12:19:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeGU6h04Ow/Listeners', 12:19:28 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 12:19:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:28 INFO - 'USER': 'cltbld', 12:19:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:28 INFO - 'XPC_FLAGS': '0x0', 12:19:28 INFO - 'XPC_SERVICE_NAME': '0', 12:19:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:28 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/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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 12:19:29 INFO - mozversion application_buildid: 20170620102757 12:19:29 INFO - mozversion application_changeset: f82ee19ca78db55bf66ae0de4cf9cbac987b67f8 12:19:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:19:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:19:29 INFO - mozversion application_name: Firefox 12:19:29 INFO - mozversion application_remotingname: firefox-dev 12:19:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:19:29 INFO - mozversion application_vendor: Mozilla 12:19:29 INFO - mozversion application_version: 55.0 12:19:29 INFO - mozversion platform_buildid: 20170620102757 12:19:29 INFO - mozversion platform_changeset: f82ee19ca78db55bf66ae0de4cf9cbac987b67f8 12:19:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:19:29 INFO - mozversion platform_version: 55.0 12:19:29 INFO - using testdate: 1497986369 12:19:29 INFO - actual date: 1497986369 12:19:29 INFO - starting webserver on 'localhost:49221' 12:19:29 INFO - SUITE-START | Running 2 tests 12:19:29 INFO - TEST-START | dromaeo_css 12:19:29 INFO - operating with platform_type : mac_ 12:19:29 INFO - Initialising browser for dromaeo_css test... 12:19:29 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpcfPHkZ/profile 12:19:29 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground) 12:20:02 INFO - TEST-INFO | 1650: killed by SIGTERM 12:20:02 INFO - Browser initialized. 12:20:02 INFO - Running cycle 1/1 for dromaeo_css test... 12:20:02 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpcfPHkZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:20:04 INFO - PID 1661 | 2017-06-20 12:20:04.243 plugin-container[1662:8089] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x974b, name = 'com.apple.tsm.portname' 12:20:04 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:04 INFO - PID 1661 | 2017-06-20 12:20:04.243 plugin-container[1662:8089] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0f, name = 'com.apple.CFPasteboardClient' 12:20:04 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:04 INFO - PID 1661 | 2017-06-20 12:20:04.243 plugin-container[1662:8089] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:20:05 INFO - PID 1661 | 2017-06-20 12:20:05.024 plugin-container[1663:8132] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 12:20:05 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:05 INFO - PID 1661 | 2017-06-20 12:20:05.025 plugin-container[1663:8132] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 12:20:05 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:05 INFO - PID 1661 | 2017-06-20 12:20:05.025 plugin-container[1663:8132] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:20:14 INFO - PID 1661 | RSS: Main: 214302720 12:20:14 INFO - PID 1661 | 12:23:19 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49221/tests/dromaeo/cssquery-ext.html) 12:23:22 INFO - PID 1661 | RSS: Main: 238948352 12:23:22 INFO - PID 1661 | 12:26:24 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-ext.html (next: http://localhost:49221/tests/dromaeo/cssquery-jquery.html) 12:26:25 INFO - PID 1661 | RSS: Main: 246665216 12:26:25 INFO - PID 1661 | 12:29:26 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49221/tests/dromaeo/cssquery-mootools.html) 12:29:27 INFO - PID 1661 | RSS: Main: 249028608 12:29:27 INFO - PID 1661 | 12:32:28 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49221/tests/dromaeo/cssquery-prototype.html) 12:32:29 INFO - PID 1661 | RSS: Main: 250687488 12:32:29 INFO - PID 1661 | 12:35:32 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49221/tests/dromaeo/cssquery-yui.html) 12:35:32 INFO - PID 1661 | RSS: Main: 251654144 12:35:32 INFO - PID 1661 | 12:38:34 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-yui.html (next: http://localhost:49221/tests/dromaeo/cssquery-dojo.html) 12:38:34 INFO - PID 1661 | RSS: Main: 249655296 12:38:34 INFO - PID 1661 | 12:38:34 INFO - PID 1661 | __start_tp_report 12:38:34 INFO - PID 1661 | _x_x_mozilla_page_load 12:38:34 INFO - PID 1661 | _x_x_mozilla_page_load_details 12:38:34 INFO - PID 1661 | |i|pagename|runs| 12:38:34 INFO - PID 1661 | |0;dojo.html;3584.8028358440274;3604.243108947121;3648.9748189754678;3693.021349342425;3700.352438323293;6278.9639709448;6284.151639528663;6301.590396624222;6321.051842223669;6323.462505687022;6314.463270622003;6372.139761132245;6386.169797926274;6394.88089584322;6411.775587854426;5540.695261760601;5562.276904002494;5570.777168913249;5664.291963504562;5703.0589952657765;5577.05190117681;5585.553155747551;5682.829515114553;5689.175069614904;5689.175069614904;5460.426655201187;5475.6440831346235;5540.50135487804;5546.057170281062;5595.860103497404;5525.4474552544825;5534.529564986981;5555.777768889239;5614.185943038277;5638.802641907555;4782.856514304576;5488.396276409591;5548.55611551075;5583.078791999315;5592.5805564582415;6063.120847477091;6314.463270622003;6361.650109243989;6365.777197798101;6367.808965731036;6655.367740064735;6916.23921368649;6916.723331066752;6934.063901373351;6939.757108501229;5462.8361149165985;6463.5798673086565;6677.232118306409;6835.043093966849;6977.33715297046;5965.433283838029;6364.268109167505;6380.457661098766;6446.77436289737;6477.190351206099;6934.098567186234;7142.035825163536;7159;7181.389581885598;7186;6639.734410623618;6647.700853461606;6655.500837437212;6657.167854018247;6658.201015878029;6841.863162736718;6846.110005699227;6854.760083397057;6976.127984002;7033.788986330418;6860.6912688929115;6863.485238607024;6874.587524748531;6891.345322194384;6912.516123871281;6887.311268873073;6889.621070841052;6889.896651550231;6910.585364878023;6910.827229319207;5358.4373640767935;5375.838724838183;5426.9185962210595;5444.6188766785945;5454.672719636835;4647.7211367317395;4858.076965376567;4917.704937703749;4927.162382395001;4967.776450059756;6327.683615819283;6393.104965304861;6439;6441.967790161019;6460.903086453707;5904.937111320004;7024.2273349931465;7029.718811247493;7036.085308909809;7039.190493093258;6390.9360906391275;6438.935610643927;6440.13058237132;6441.742330306829;6444.7744328948265;5905.822825315247;5992.550558708202;5997.850053748604;6025.0359942409;6133.570650054543;7016.859662806613;7073.893891591733;7074.327938846007;7087.043249161504;7150.606716630636;6979.4765392594745;7029.05107810439;7048.154221493453;7049.224585295511;7071.929280707127;6282.528810339334;6827.453803695792;7174.784756457315;7201.459890508128;7201.927980720126;6883.690233939385;6908.965455172692;6919.135108111486;6931.272216417406;6949.166100068024;6781.830454238683;6833.692483838338;6881.621510816753;6883.208431030495;7046.506744527834;6637.70130344126;6667.599944003376;6756.189257289157;6771.695273712597;6776.93223067763;6670.366315200048;6672.966635166793;6728.798136055926;6760.93239067603;6773.254941956479;6443.581167224115;6451.870962580629;6616.371444712745;6646.60120392778;6669.599824010575;6577.408033277028;6582.572132811352;6595.208574971034;6597.373249541286;6627.5360724750735;6478.70845811949;6565.048068030204;6585.539012269095;6606.306337834651;6635.767748128792 12:38:34 INFO - PID 1661 | |1;ext.html;760.8021914302279;822.2763967708415;844.8479273730728;862.7368652560971;867.7570280321512;37661.81169094154;38458;38467.80766096173;38468.6535971241;38649.61350386495;37953.810230948875;37982.81008594953;38196.42705359422;38265.80867095668;38374.4243836342;6372.872542549147;7549.018627578407;7575.621218939031;7585.279398457165;7586.962065189667;7461.552306861606;7526.285002924741;7567.432442566802;7572.091349038124;7577.393808495307;7519.548827070366;7544.0947086349715;7550.471466997313;7555.16893141754;7560.281773231562;7506.399488040971;7519.962400187964;7525.849483010309;7533.359664428557;7567.470277080606;6881.518293719441;8109.351251899834;8273.420860539765;8286.792830179294;8306.252437280673;36563.268734625155;36750.63249367499;36894.44658330127;36904.81547592245;36928.81535592332;3528.5942116656656;3575.302815950891;3579.49887015818;3581.3195492856275;3583.7133029357587;30169;30525;30537.69462305393;30564;30666.386672266653;3046.588710524073;3137.137287246003;3148.2916343822685;3164.6360668523184;3173.825439600821;267957;269790.6510467455;273555;330514;334094;48177;48767.5123248763;48795.756021220375;48805.755971219914;48923;50864.23703644448;50901.490985090415;50979;51028.23457648138;51089.978200436526;15404;15511.224438778241;15549.611259718371;15559.922200389152;15615.687686246212;12844.908182804566;12860.871391285968;12864.099513033998;12874;12882.677933051562;13639.181649101085;13670.794938075936;13699.246541440316;13720.725585488235;13792.310384480936;5774.855628609318;5975.163477113208;6003.249593800775;6023.969880150659;6040.939590604125;1007.8538611901233;1008.3848852200152;1019.673704414595;1069.8288273876142;1080.7460246841986;18376.632467350846;18662.813371866378;20412.591748164952;22687.773122268896;23502.764972350058;1053.5469748008277;1076.7200527862656;1090.6019302954364;1124.746931940307;1150.2006105756418;11607.6517704469;11940.641780746591;11957.34234617087;12309.199902006341;12340.691482713004;343985;348858;349370;350169;350270;53605.46394536005;54026.45973540293;54042.729786350814;54071;54146.18780718293;1507.5100592307545;1540.6148462884055;1556.0430335343908;1561.3832536148159;1571.088768514259;22086;22478.775212247667;22881.542369152856;23092.076316947136;24217;37650.246995060494;37825.81087094547;37831.81084094562;37833.810830945666;37851.24297514089;270331;271712.2828771687;272855;272895;273477;33958;34545.48181777225;35852.462213067345;35935.82032089823;36647;42191.57808421877;42558.787206063775;44234;44423.55576444194;44494.11011779811;27279.86360068187;28798.280042999093;30622.387552249274;31339.686603133676;31597.368052639278;28799.712002879704;29284.41431171322;30496;30674.539881902234;30992.69007309898;30017.699823002364;37242.25515489729;37273.81363093276;37332.06669833167;37363.252734945694;26906.32734181661;29608.703912960595;29648.703512965458;31233.219169520944;31553.21116972094;22987.425314366606;24303.392415189763;24450.51098978046;24498.142565010137;24517.632235516125 12:38:34 INFO - PID 1661 | |2;jquery.html;479.2140888942135;508.85507607882266;513.3531749995008;515.5437437867487;533.1416419564503;18805.71791423128;21866.781332186674;22015.669764953538;22330.665040024414;22817.08731650734;24010.51978960424;24508.897099630514;24736.75263247367;24764;24829.62755558668;6374.745010199586;6594.6043237405665;6600.735970561172;6608.2730899601;6616.966915165417;6448.355164483561;6548.1814773153355;6555.0495178199135;6583;6599.604023758566;5870.501007414375;6450.4194622483965;6453.580517266386;6478.967605161967;6506.0891475193275;6382.20222472191;6417.871642567123;6440.80677579671;6444.7744328948265;6454.93545064548;5009.2486127080865;5869.236999190078;5872.970635146797;5874.941250587525;5882.764689412418;27794.6646402431;28188.71811281902;28627.57058644122;28792.280192995342;28914.27714307138;2293.2317673579373;2305.9077636894503;2325.395397196724;2338.076459798387;2341.519988402373;18137.909310453364;18544.980026098558;18596.07019649012;18638.90680546602;18644;2027.432318950696;2128.3508529898377;2172.6089303925287;2202.548477562112;2265.6374980003284;135509;172645.99999999875;174206;174790;178938;13593.86406135926;13664.590062298146;14857.702845943237;15347.462838800588;15371.231438428034;19841.702374464396;20082.598348032956;20120.496987575134;20142.496437588885;20151.395458136278;10129.139023183112;10209.438480883475;10224.64213752515;10301.74245643865;10309.226807989431;8714.38999270045;8968.417052891542;9521.523923803783;9667.903320966841;9737.805243895224;7462.179160292363;8870.334724895658;8973.32700047499;9049.230815380766;9057.184853363096;2662.4142688608476;2678.39736059389;2680.2629276948605;2683.9731602684114;2693.7575618194455;993.9403635781877;996.5515518016921;1013.7668336282696;1062.8733542445034;1073.2380010192694;17076.487705368858;21323;21424.98721165329;22171.00230489632;22490.437739056328;892.238521775105;1016.0601443664581;1046.1160319529986;1058.7459009837737;1080.9243352965218;10862.456877156268;11140.144412973148;11210.663680089447;11212.943935280271;11456.713582160346;484334;569858;613524;619357;628408;162620.18689906475;169058;173978;174706;176160;1215.2040413529153;1256.4094875408543;1259.8740125987329;1264.8545417276946;1267.1700036476307;21826.454338641026;22891.08435662555;23538.646920295865;23593;23783.167589134297;66756;71459;73524;75460.62269688616;75935;100114.4994275024;100955;102293.97706022844;104546;105126;10817.242793004414;11684.474198660911;11789.351585662527;11806.1145414096;11977;18399;18445.354412595494;18601.627967440847;18694.34569790051;18902.164675298816;14272.500462483764;14576.562703118923;14822.925885370503;15176.24118794078;15196.468123615621;13136;13226.470941162246;13666.316684165951;13854.445822167001;14044.368003439666;15013.474528391454;15598.610034749223;16129.11289879068;16130.274137663599;16138.596535086717;11902.464389102339;11916.76166476683;11943.940280298542;11962.10284228669;11975.221610595283;13970.930145349208;14947.551573452814;15067.4726384576;15073.944823862224;15088.471903483325 12:38:34 INFO - PID 1661 | |3;mootools.html;1283.274949653446;1459.9342479989607;1460.8100946876898;1462.7732701431282;1465.8973871828973;11850.3482308473;13872;13887.722245555084;13892.583222503341;13920.860791392095;13319.467221311135;13688.589342319747;13692.041557091008;13705;13742.931285343584;2393.5571919194917;2852.985735071322;2858.985705071472;2859.7426231639147;2863.2841789552613;2296.5062511560095;2819.7321254480894;2821.858907054639;2823.8023338366324;2825.1242114944434;2661.72051934546;2778.569321755131;2824.8305101693963;2830.037787152368;2847.9857600711966;2821.2523681224493;2843.2323272716458;2843.360243945106;2843.8862445502364;2851.17315978366;2773.2234974207254;2793.050362876632;2797.776177905783;2797.9088155619324;2808.8455134967567;13191.736165276736;13457.058005939589;13486.527971521047;13490.730185396236;13509.729805403935;7356.595387253699;7606.65770040344;7643.770686879402;7651.311381975594;7667.463277570572;4542.454905411372;4684.227102528079;4712.175369310357;4779.569838714532;4782.808687652489;1373.6703191234033;1376.43566137884;1377.8759911607904;1394.8256467941508;1400.4188261871311;20379.4905127373;20554.5889082219;20607.793922060737;20627.48431289215;20640;5715.657060576379;6531.477481801445;6715.496337774686;6721.529492935448;6804.591724496546;13283.468661253635;13392.933035334761;13421.39603717835;13442.932785336207;13457.730845383037;7307.1231452226075;7420.5547667140145;7427.294407031323;7437.776866694008;7448.627568621656;6569.113169722122;6789.25318214996;6862.759803406957;6879;6935.3411425914455;4545.931811022771;4939.925901111486;5058.064258112261;5082.491750824962;5135.409427915837;1493.992530037343;1511.60698218464;1522.0943538594518;1531.9004264722757;1536.9231538423257;816.3550794872103;828.3166387730147;833.6040380819062;839.3578912132224;864.5936409887343;6978.860422791516;7155.427565794724;7200.927990720131;7246;7264.839794689327;666.3136968922018;674.8987651852163;694.1150033707064;708.19619731605;709.7480394459996;3625.782453052825;4762.857114286577;5075.314832497639;5392.91910621341;5471.452854714496;18572.978486183118;18638.813611863978;18864.15111319994;18894.905525472284;18914.43256702301;18180.363687271147;18366.265349385874;18515.259389624265;18969.051547422572;19001.239950402105;9282.953585232031;9302.488363140092;9563.760905977268;9633.662821801203;9643.517824108767;7915.168907264884;7939.84120317602;8186.140455252113;8415.663373464993;8587.957060214909;16596.58508537296;16727.91636041812;16971.575710607332;17261.56846078858;17283.7407438886;17399.56501087483;17604.91197544004;17736.467905962843;17793.199306031518;17912.820871791115;8650.43772154808;9400.529973501161;10319.690409287734;10340.741481463023;10367.896321036693;12473.563425280072;12595.433205505591;12616.36918154107;12618.49526018949;12653.430595623035;8989.46063236208;9822.803543929223;9998.200143988319;10292.485375731334;10449.582016719247;9497;9695.660651877433;10125.189984801345;10206.642767503101;10394.064534191657;9171.63313467454;9184.816303674022;9208.309376796633;9308.208802251884;9326.347155699035;6385.9460252991485;6501.2523559791225;6938.8612227756175;6996.965015174892;7006.684699188447;6712.328767123444;6938.653067346713;7009.264027277266;7147.892781608176;7184.497085203986 12:38:34 INFO - PID 1661 | |4;prototype.html;408.92026054919296;439.5670264789184;467.9321498382736;477.2149813556699;480.98557043288713;6363.681815909209;6468.741250349986;6590.538662293641;6595.142631457916;6614.702338394772;5649.971750141243;6203.782867599635;6349.936500634993;6354.650494222816;6429.517786166033;4137.8758637240935;4808.158572249842;4815.133276010317;4831.227003679412;4837.201861692825;3557.2351944331963;4804.183288840905;4812.855614331575;4830.9033819323595;4861.659683822116;3720.330340538702;4231.576842315759;4299.785010749466;4495.797689103998;4808.543188397097;3768.5854555998817;4387.990762124713;4608.262677971542;4766.713997160165;4798.184308667535;4059.147579008412;4222.725522841006;4234.195502854448;4240.321548552248;4244.575542445731;5968.224130863004;6316.30520642729;6324.272708638474;6324.747010119636;6344.873102537924;5716.857078573027;5748.7987920423;5749.511291540224;5753.424657534255;5767.5962682612235;6957.478189135835;6970.93029069713;6981.441484681213;6990;7007.649617519103;4173.520045194811;4848.878778030542;4865.464798872121;4868.221084626478;4889.3643826302705;6708.899366509458;6803.353681400308;6947.756828511028;7062.752803651848;7327.702533859731;4939.185034469344;4946.463278755707;4948.183549714328;4948.331975183376;4955.331030310934;5011.899762004812;5029.597632189416;5038.395392552917;5046.84859454217;5078.593712503065;5034.647574669812;5060.671056381323;5497.230387745719;5545.195946587803;5766.048601980626;6582.605043697297;6918.93081069193;6981.581105133709;7018.33325834045;7033.869927882146;1582.3512359932372;1693.865110376051;1698.9660206795795;1704.2927185218243;1706.3089448773267;1062.5431064642123;1111.5276007696696;1145.7364806094645;1157.9189456737947;1170.9941450292695;5504.862378440491;5707.686077265707;6192.659403732838;6344.524160687967;6450.903236451457;6731.427828634523;7034.331738484836;7098.787036388917;7104.3961263293195;7199.1361036675935;5926.496247818897;5940.584159108907;6378.170837791057;6383.2978372379;6420.367649263872;3401.6598340165783;3728.8881333560034;3730.0861288984374;3738.5700644425697;3779.829907654107;6239.469645080219;7192.065031545865;7334.17634210822;7364.784810506314;7384.2246564111065;6316.399942005594;6369.490440764727;6601.108850305243;6897.48268879836;7349.669264883093;7301.598412087386;7328.30381113804;7330.853382932311;7337.229590892986;7399.852002960019;5578.051731205786;5913.66942437948;5951.404859514014;6929.549579277331;7118.359347658736;5647.3787883333625;6673.265940746611;7044.894326585208;7059.399951004017;7185.892211616725;5753.4246575343805;6700.798976030727;6939.514234003571;7259.6007219603425;7386.269933683456;5086.3896332440345;5494.2033405155225;5511.118221084607;5525.955396503319;5556.499915007668;7175.138983322035;7176.96411517939;7198.4601154914635;7201.531900426456;7215.530990485601;6825.863482730417;6895.069165662772;6896.931030689629;6908.205556360882;6952.791416257521;6014.609050411709;6065.332813390612;6632.568883022588;6899.517033807585;6913.654317284217;6245.6252624842655;6714.261431242462;6728.058071869844;6742.4943129266485;6783.898241526283;6853.246142924374;6860.108185935996;6866.210385805696;6906.965465172642;6916.93083069183;5800.912986305125;6157.692115394123;6641.468682505291;6679.666016699049;6685.465162786868 12:38:34 INFO - PID 1661 | |5;yui.html;718.3929579505316;805.8186907945712;846.7629063862117;869.7043005378171;875.5228400521715;16627.251773670185;16987;17172.055536945463;17208.48374548764;17211.3115475381;16881.493555193363;18520.073996300198;18540.351087711933;18570.35003774865;18570.907145464254;1003.7189586915675;1021.8824835143979;1032.2052019944638;1033.018632299315;1034.260503739826;1041.7916416716648;1042.1662669864115;1042.2599954032646;1045.7385653586603;1053.1995683280722;1039.428314427066;1073.2380010192774;1083.3770581915392;1092.748667806405;1092.9781404371909;1068.2895874243618;1077.5097330714507;1077.8437126616636;1082.3830416662506;1086.4296244471636;789.5973053742575;789.996704185681;792.7621713485988;793.5635400529743;799.2367289238821;14304.57086287413;16521.00873947555;16536.090515021668;16573.83426165735;17021.574460638458;17440.738388924176;17598.736018959728;17640.294388224454;17851;18070.457886263433;14680.04579702316;14692.412303507846;14850.925745371313;14857.405703771836;14879.03286286388;3334.3498017886277;3453.0849324928913;3577.1951310955087;3589.210373717778;3597.7661452005536;133376.66623333836;136186;136785.31607342;138124;143146;14036.29818509091;14210.786838197435;14229.003969722227;14253.714925701635;14394.712105758035;26907;27039.864800676263;27312.453750925266;27366.726332736416;27435.451290974466;7992.960035199787;8204;8215.137410571804;8318.958405207935;8333.250007499355;4043.534993475729;4129.236091323055;4131.35963925592;4134.3178375567695;4134.400511925754;4595.655325850575;4671.112488627218;4714;4998.9500104999215;5207.270982062514;843.5149788871423;1027.773889744255;1047.2878442658912;1051.8106740786732;1053.8208504554243;578.5602941764289;626.1671976271548;635.5138319185827;660.7654282729658;679.341039191983;10740.174170391052;11297.661070167907;11459.427028648533;11668.766624667629;12054.457549410297;620.4106099205773;628.6542401679059;645.4545908707229;662.3211208511218;668.4318329419954;4968.27959945813;5400.810971615974;5620.494155526022;5623.184638227516;5873.588848780544;107032;145000;151468;151848;154197.2290138542;127203.72796272104;127608;127862.36068819596;131756;131848;101718;115945;116344;118702.81297187091;118944;9039.644053391781;10141.746456338651;10692.358458492516;10779.245452818228;11262;45248.32127518024;45372;45816;47653.76173119112;48262.75868620635;63637.68181159064;65354.67322663547;68666.62666746737;68937;69178.65410672914;14328.570142895729;14331.426742930584;14341.85658143447;14659.120452772868;14686.485972990895;14957.626059348604;15437.614059648598;15861.682766344838;15876.285567149274;16026.198690065683;15010.399584016517;17941.372051978116;18351.632967340312;18366.449006529827;18425;16905.070221137954;17265.482035538953;17290.567735806704;17467.30130794754;17473.301067957138;15552.533423997298;16253.943493672874;16498.34006639721;16640.83359166393;17038.233279502205;9671.081247281498;10290.485475726333;10629.681109566725;12024.458899349374;12382.81425778632;12884.484620615445;13028;13441.193528388329;13465.259410732504;13980.860191397956 12:38:34 INFO - PID 1661 | __end_tp_report 12:38:34 INFO - PID 1661 | __start_cc_report 12:38:34 INFO - PID 1661 | _x_x_mozilla_cycle_collect,398 12:38:34 INFO - PID 1661 | __end_cc_report 12:38:34 INFO - PID 1661 | __startTimestamp1497987514740__endTimestamp 12:38:34 INFO - PID 1661 | 12:38:34 INFO - PID 1661 | ------- Summary: start ------- 12:38:34 INFO - PID 1661 | Number of tests: 6 12:38:34 INFO - PID 1661 | 12:38:34 INFO - PID 1661 | [#0] dojo.html Cycles:165 Average:6317.75 Median:6565.05 stddev:762.38 (11.6%) stddev-sans-first:733.96 12:38:34 INFO - PID 1661 | Values: 3584.8 3604.2 3649.0 3693.0 3700.4 6279.0 6284.2 6301.6 6321.1 6323.5 6314.5 6372.1 6386.2 6394.9 6411.8 5540.7 5562.3 5570.8 5664.3 5703.1 5577.1 5585.6 5682.8 5689.2 5689.2 5460.4 5475.6 5540.5 5546.1 5595.9 5525.4 5534.5 5555.8 5614.2 5638.8 4782.9 5488.4 5548.6 5583.1 5592.6 6063.1 6314.5 6361.7 6365.8 6367.8 6655.4 6916.2 6916.7 6934.1 6939.8 5462.8 6463.6 6677.2 6835.0 6977.3 5965.4 6364.3 6380.5 6446.8 6477.2 6934.1 7142.0 7159.0 7181.4 7186.0 6639.7 6647.7 6655.5 6657.2 6658.2 6841.9 6846.1 6854.8 6976.1 7033.8 6860.7 6863.5 6874.6 6891.3 6912.5 6887.3 6889.6 6889.9 6910.6 6910.8 5358.4 5375.8 5426.9 5444.6 5454.7 4647.7 4858.1 4917.7 4927.2 4967.8 6327.7 6393.1 6439.0 6442.0 6460.9 5904.9 7024.2 7029.7 7036.1 7039.2 6390.9 6438.9 6440.1 6441.7 6444.8 5905.8 5992.6 5997.9 6025.0 6133.6 7016.9 7073.9 7074.3 7087.0 7150.6 6979.5 7029.1 7048.2 7049.2 7071.9 6282.5 6827.5 7174.8 7201.5 7201.9 6883.7 6909.0 6919.1 6931.3 6949.2 6781.8 6833.7 6881.6 6883.2 7046.5 6637.7 6667.6 6756.2 6771.7 6776.9 6670.4 6673.0 6728.8 6760.9 6773.3 6443.6 6451.9 6616.4 6646.6 6669.6 6577.4 6582.6 6595.2 6597.4 6627.5 6478.7 6565.0 6585.5 6606.3 6635.8 12:38:34 INFO - PID 1661 | 12:38:34 INFO - PID 1661 | [#1] ext.html Cycles:180 Average:45357.04 Median:349769.50 stddev:80829.22 (23.1%) stddev-sans-first:80986.61 12:38:34 INFO - PID 1661 | Values: 760.8 822.3 844.8 862.7 867.8 37661.8 38458.0 38467.8 38468.7 38649.6 37953.8 37982.8 38196.4 38265.8 38374.4 6372.9 7549.0 7575.6 7585.3 7587.0 7461.6 7526.3 7567.4 7572.1 7577.4 7519.5 7544.1 7550.5 7555.2 7560.3 7506.4 7520.0 7525.8 7533.4 7567.5 6881.5 8109.4 8273.4 8286.8 8306.3 36563.3 36750.6 36894.4 36904.8 36928.8 3528.6 3575.3 3579.5 3581.3 3583.7 30169.0 30525.0 30537.7 30564.0 30666.4 3046.6 3137.1 3148.3 3164.6 3173.8 267957.0 269790.7 273555.0 330514.0 334094.0 48177.0 48767.5 48795.8 48805.8 48923.0 50864.2 50901.5 50979.0 51028.2 51090.0 15404.0 15511.2 15549.6 15559.9 15615.7 12844.9 12860.9 12864.1 12874.0 12882.7 13639.2 13670.8 13699.2 13720.7 13792.3 5774.9 5975.2 6003.2 6024.0 6040.9 1007.9 1008.4 1019.7 1069.8 1080.7 18376.6 18662.8 20412.6 22687.8 23502.8 1053.5 1076.7 1090.6 1124.7 1150.2 11607.7 11940.6 11957.3 12309.2 12340.7 343985.0 348858.0 349370.0 350169.0 350270.0 53605.5 54026.5 54042.7 54071.0 54146.2 1507.5 1540.6 1556.0 1561.4 1571.1 22086.0 22478.8 22881.5 23092.1 24217.0 37650.2 37825.8 37831.8 37833.8 37851.2 270331.0 271712.3 272855.0 272895.0 273477.0 33958.0 34545.5 35852.5 35935.8 36647.0 42191.6 42558.8 44234.0 44423.6 44494.1 27279.9 28798.3 30622.4 31339.7 31597.4 28799.7 29284.4 30496.0 30674.5 30992.7 30017.7 37242.3 37273.8 37332.1 37363.3 26906.3 29608.7 29648.7 31233.2 31553.2 22987.4 24303.4 24450.5 24498.1 24517.6 12:38:34 INFO - PID 1661 | 12:38:34 INFO - PID 1661 | [#2] jquery.html Cycles:180 Average:40327.41 Median:11725.68 stddev:100783.46 (859.5%) stddev-sans-first:101021.78 12:38:34 INFO - PID 1661 | Values: 479.2 508.9 513.4 515.5 533.1 18805.7 21866.8 22015.7 22330.7 22817.1 24010.5 24508.9 24736.8 24764.0 24829.6 6374.7 6594.6 6600.7 6608.3 6617.0 6448.4 6548.2 6555.0 6583.0 6599.6 5870.5 6450.4 6453.6 6479.0 6506.1 6382.2 6417.9 6440.8 6444.8 6454.9 5009.2 5869.2 5873.0 5874.9 5882.8 27794.7 28188.7 28627.6 28792.3 28914.3 2293.2 2305.9 2325.4 2338.1 2341.5 18137.9 18545.0 18596.1 18638.9 18644.0 2027.4 2128.4 2172.6 2202.5 2265.6 135509.0 172646.0 174206.0 174790.0 178938.0 13593.9 13664.6 14857.7 15347.5 15371.2 19841.7 20082.6 20120.5 20142.5 20151.4 10129.1 10209.4 10224.6 10301.7 10309.2 8714.4 8968.4 9521.5 9667.9 9737.8 7462.2 8870.3 8973.3 9049.2 9057.2 2662.4 2678.4 2680.3 2684.0 2693.8 993.9 996.6 1013.8 1062.9 1073.2 17076.5 21323.0 21425.0 22171.0 22490.4 892.2 1016.1 1046.1 1058.7 1080.9 10862.5 11140.1 11210.7 11212.9 11456.7 484334.0 569858.0 613524.0 619357.0 628408.0 162620.2 169058.0 173978.0 174706.0 176160.0 1215.2 1256.4 1259.9 1264.9 1267.2 21826.5 22891.1 23538.6 23593.0 23783.2 66756.0 71459.0 73524.0 75460.6 75935.0 100114.5 100955.0 102294.0 104546.0 105126.0 10817.2 11684.5 11789.4 11806.1 11977.0 18399.0 18445.4 18601.6 18694.3 18902.2 14272.5 14576.6 14822.9 15176.2 15196.5 13136.0 13226.5 13666.3 13854.4 14044.4 15013.5 15598.6 16129.1 16130.3 16138.6 11902.5 11916.8 11943.9 11962.1 11975.2 13970.9 14947.6 15067.5 15073.9 15088.5 12:38:34 INFO - PID 1661 | 12:38:34 INFO - PID 1661 | [#3] mootools.html Cycles:180 Average:8313.73 Median:2843.62 stddev:5601.93 (197.0%) stddev-sans-first:5592.73 12:38:34 INFO - PID 1661 | Values: 1283.3 1459.9 1460.8 1462.8 1465.9 11850.3 13872.0 13887.7 13892.6 13920.9 13319.5 13688.6 13692.0 13705.0 13742.9 2393.6 2853.0 2859.0 2859.7 2863.3 2296.5 2819.7 2821.9 2823.8 2825.1 2661.7 2778.6 2824.8 2830.0 2848.0 2821.3 2843.2 2843.4 2843.9 2851.2 2773.2 2793.1 2797.8 2797.9 2808.8 13191.7 13457.1 13486.5 13490.7 13509.7 7356.6 7606.7 7643.8 7651.3 7667.5 4542.5 4684.2 4712.2 4779.6 4782.8 1373.7 1376.4 1377.9 1394.8 1400.4 20379.5 20554.6 20607.8 20627.5 20640.0 5715.7 6531.5 6715.5 6721.5 6804.6 13283.5 13392.9 13421.4 13442.9 13457.7 7307.1 7420.6 7427.3 7437.8 7448.6 6569.1 6789.3 6862.8 6879.0 6935.3 4545.9 4939.9 5058.1 5082.5 5135.4 1494.0 1511.6 1522.1 1531.9 1536.9 816.4 828.3 833.6 839.4 864.6 6978.9 7155.4 7200.9 7246.0 7264.8 666.3 674.9 694.1 708.2 709.7 3625.8 4762.9 5075.3 5392.9 5471.5 18573.0 18638.8 18864.2 18894.9 18914.4 18180.4 18366.3 18515.3 18969.1 19001.2 9283.0 9302.5 9563.8 9633.7 9643.5 7915.2 7939.8 8186.1 8415.7 8588.0 16596.6 16727.9 16971.6 17261.6 17283.7 17399.6 17604.9 17736.5 17793.2 17912.8 8650.4 9400.5 10319.7 10340.7 10367.9 12473.6 12595.4 12616.4 12618.5 12653.4 8989.5 9822.8 9998.2 10292.5 10449.6 9497.0 9695.7 10125.2 10206.6 10394.1 9171.6 9184.8 9208.3 9308.2 9326.3 6385.9 6501.3 6938.9 6997.0 7006.7 6712.3 6938.7 7009.3 7147.9 7184.5 12:38:34 INFO - PID 1661 | 12:38:34 INFO - PID 1661 | [#4] prototype.html Cycles:180 Average:5585.15 Median:6198.22 stddev:1710.20 (27.6%) stddev-sans-first:1670.29 12:38:34 INFO - PID 1661 | Values: 408.9 439.6 467.9 477.2 481.0 6363.7 6468.7 6590.5 6595.1 6614.7 5650.0 6203.8 6349.9 6354.7 6429.5 4137.9 4808.2 4815.1 4831.2 4837.2 3557.2 4804.2 4812.9 4830.9 4861.7 3720.3 4231.6 4299.8 4495.8 4808.5 3768.6 4388.0 4608.3 4766.7 4798.2 4059.1 4222.7 4234.2 4240.3 4244.6 5968.2 6316.3 6324.3 6324.7 6344.9 5716.9 5748.8 5749.5 5753.4 5767.6 6957.5 6970.9 6981.4 6990.0 7007.6 4173.5 4848.9 4865.5 4868.2 4889.4 6708.9 6803.4 6947.8 7062.8 7327.7 4939.2 4946.5 4948.2 4948.3 4955.3 5011.9 5029.6 5038.4 5046.8 5078.6 5034.6 5060.7 5497.2 5545.2 5766.0 6582.6 6918.9 6981.6 7018.3 7033.9 1582.4 1693.9 1699.0 1704.3 1706.3 1062.5 1111.5 1145.7 1157.9 1171.0 5504.9 5707.7 6192.7 6344.5 6450.9 6731.4 7034.3 7098.8 7104.4 7199.1 5926.5 5940.6 6378.2 6383.3 6420.4 3401.7 3728.9 3730.1 3738.6 3779.8 6239.5 7192.1 7334.2 7364.8 7384.2 6316.4 6369.5 6601.1 6897.5 7349.7 7301.6 7328.3 7330.9 7337.2 7399.9 5578.1 5913.7 5951.4 6929.5 7118.4 5647.4 6673.3 7044.9 7059.4 7185.9 5753.4 6700.8 6939.5 7259.6 7386.3 5086.4 5494.2 5511.1 5526.0 5556.5 7175.1 7177.0 7198.5 7201.5 7215.5 6825.9 6895.1 6896.9 6908.2 6952.8 6014.6 6065.3 6632.6 6899.5 6913.7 6245.6 6714.3 6728.1 6742.5 6783.9 6853.2 6860.1 6866.2 6907.0 6916.9 5800.9 6157.7 6641.5 6679.7 6685.5 12:38:34 INFO - PID 1661 | 12:38:34 INFO - PID 1661 | [#5] yui.html Cycles:180 Average:25664.64 Median:16634.04 stddev:39746.46 (238.9%) stddev-sans-first:39813.83 12:38:34 INFO - PID 1661 | Values: 718.4 805.8 846.8 869.7 875.5 16627.3 16987.0 17172.1 17208.5 17211.3 16881.5 18520.1 18540.4 18570.4 18570.9 1003.7 1021.9 1032.2 1033.0 1034.3 1041.8 1042.2 1042.3 1045.7 1053.2 1039.4 1073.2 1083.4 1092.7 1093.0 1068.3 1077.5 1077.8 1082.4 1086.4 789.6 790.0 792.8 793.6 799.2 14304.6 16521.0 16536.1 16573.8 17021.6 17440.7 17598.7 17640.3 17851.0 18070.5 14680.0 14692.4 14850.9 14857.4 14879.0 3334.3 3453.1 3577.2 3589.2 3597.8 133376.7 136186.0 136785.3 138124.0 143146.0 14036.3 14210.8 14229.0 14253.7 14394.7 26907.0 27039.9 27312.5 27366.7 27435.5 7993.0 8204.0 8215.1 8319.0 8333.3 4043.5 4129.2 4131.4 4134.3 4134.4 4595.7 4671.1 4714.0 4999.0 5207.3 843.5 1027.8 1047.3 1051.8 1053.8 578.6 626.2 635.5 660.8 679.3 10740.2 11297.7 11459.4 11668.8 12054.5 620.4 628.7 645.5 662.3 668.4 4968.3 5400.8 5620.5 5623.2 5873.6 107032.0 145000.0 151468.0 151848.0 154197.2 127203.7 127608.0 127862.4 131756.0 131848.0 101718.0 115945.0 116344.0 118702.8 118944.0 9039.6 10141.7 10692.4 10779.2 11262.0 45248.3 45372.0 45816.0 47653.8 48262.8 63637.7 65354.7 68666.6 68937.0 69178.7 14328.6 14331.4 14341.9 14659.1 14686.5 14957.6 15437.6 15861.7 15876.3 16026.2 15010.4 17941.4 18351.6 18366.4 18425.0 16905.1 17265.5 17290.6 17467.3 17473.3 15552.5 16253.9 16498.3 16640.8 17038.2 9671.1 10290.5 10629.7 12024.5 12382.8 12884.5 13028.0 13441.2 13465.3 13980.9 12:38:34 INFO - PID 1661 | -------- Summary: end -------- 12:38:34 INFO - PID 1661 | 12:38:35 INFO - TEST-INFO | 1661: exit 0 12:38:35 INFO - TEST-OK | dromaeo_css | took 1146321ms 12:38:35 INFO - TEST-START | kraken 12:38:35 INFO - operating with platform_type : mac_ 12:38:35 INFO - Initialising browser for kraken test... 12:38:35 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpI63hhb/profile 12:38:35 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49221/getInfo.html -foreground) 12:39:08 INFO - TEST-INFO | 1673: killed by SIGTERM 12:39:08 INFO - Browser initialized. 12:39:08 INFO - Running cycle 1/1 for kraken test... 12:39:08 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpI63hhb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:39:10 INFO - PID 1677 | 2017-06-20 12:39:10.583 plugin-container[1678:10704] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454f, name = 'com.apple.tsm.portname' 12:39:10 INFO - PID 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:39:10 INFO - PID 1677 | 2017-06-20 12:39:10.584 plugin-container[1678:10704] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9813, name = 'com.apple.CFPasteboardClient' 12:39:10 INFO - PID 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:39:10 INFO - PID 1677 | 2017-06-20 12:39:10.584 plugin-container[1678:10704] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:39:11 INFO - PID 1677 | 2017-06-20 12:39:11.370 plugin-container[1679:10747] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 12:39:11 INFO - PID 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:39:11 INFO - PID 1677 | 2017-06-20 12:39:11.371 plugin-container[1679:10747] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 12:39:11 INFO - PID 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:39:11 INFO - PID 1677 | 2017-06-20 12:39:11.371 plugin-container[1679:10747] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:39:20 INFO - PID 1677 | RSS: Main: 215019520 12:39:20 INFO - PID 1677 | 12:39:23 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection) 12:39:24 INFO - PID 1677 | RSS: Main: 228868096 12:39:24 INFO - PID 1677 | 12:39:27 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-dft) 12:39:27 INFO - PID 1677 | RSS: Main: 238411776 12:39:27 INFO - PID 1677 | 12:39:30 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-fft) 12:39:31 INFO - PID 1677 | RSS: Main: 244011008 12:39:31 INFO - PID 1677 | 12:39:33 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator) 12:39:34 INFO - PID 1677 | RSS: Main: 245202944 12:39:34 INFO - PID 1677 | 12:39:36 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:39:36 INFO - PID 1677 | RSS: Main: 245723136 12:39:36 INFO - PID 1677 | 12:39:39 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom) 12:39:40 INFO - PID 1677 | RSS: Main: 250753024 12:39:40 INFO - PID 1677 | 12:39:43 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate) 12:39:43 INFO - PID 1677 | RSS: Main: 250503168 12:39:43 INFO - PID 1677 | 12:39:46 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial) 12:39:47 INFO - PID 1677 | RSS: Main: 251142144 12:39:47 INFO - PID 1677 | 12:39:48 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:39:49 INFO - PID 1677 | RSS: Main: 250593280 12:39:49 INFO - PID 1677 | 12:39:51 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:39:51 INFO - PID 1677 | RSS: Main: 249835520 12:39:51 INFO - PID 1677 | 12:39:53 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:39:54 INFO - PID 1677 | RSS: Main: 249802752 12:39:54 INFO - PID 1677 | 12:39:57 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:39:58 INFO - PID 1677 | RSS: Main: 250404864 12:39:58 INFO - PID 1677 | 12:40:01 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:40:01 INFO - PID 1677 | RSS: Main: 250101760 12:40:01 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49221/tests/kraken/driver.html?testName=ai-astar) 12:40:03 INFO - PID 1677 | RSS: Main: 251822080 12:40:03 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | __start_tp_report 12:40:03 INFO - PID 1677 | _x_x_mozilla_page_load 12:40:03 INFO - PID 1677 | _x_x_mozilla_page_load_details 12:40:03 INFO - PID 1677 | |i|pagename|runs| 12:40:03 INFO - PID 1677 | |0;ai-astar;125;106;110;168;157;116;115;110;108;96 12:40:03 INFO - PID 1677 | |1;audio-beat-detection;138;115;141;142;111;123;114;147;146;121 12:40:03 INFO - PID 1677 | |2;audio-dft;199;202;201;199;196;190;193;197;199;194 12:40:03 INFO - PID 1677 | |3;audio-fft;73;73;81;148;59;56;60;65;123;58 12:40:03 INFO - PID 1677 | |4;audio-oscillator;69;70;113;70;70;69;106;67;77;133 12:40:03 INFO - PID 1677 | |5;imaging-gaussian-blur;89;96;90;88;88;89;89;89;108;88 12:40:03 INFO - PID 1677 | |6;imaging-darkroom;96;98;96;96;97;96;96;97;95;96 12:40:03 INFO - PID 1677 | |7;imaging-desaturate;92;95;91;90;93;135;143;91;90;118 12:40:03 INFO - PID 1677 | |8;json-parse-financial;48;47;46;53;50;52;53;53;52;53 12:40:03 INFO - PID 1677 | |9;json-stringify-tinderbox;54;56;55;55;58;55;57;56;57;55 12:40:03 INFO - PID 1677 | |10;stanford-crypto-aes;79;81;103;100;85;79;79;79;79;77 12:40:03 INFO - PID 1677 | |11;stanford-crypto-ccm;134;136;133;133;131;137;151;150;139;159 12:40:03 INFO - PID 1677 | |12;stanford-crypto-pbkdf2;194;220;175;222;224;183;182;187;237;176 12:40:03 INFO - PID 1677 | |13;stanford-crypto-sha256-iterative;63;67;64;63;65;64;64;63;64;66 12:40:03 INFO - PID 1677 | __end_tp_report 12:40:03 INFO - PID 1677 | __start_cc_report 12:40:03 INFO - PID 1677 | _x_x_mozilla_cycle_collect,1031 12:40:03 INFO - PID 1677 | __end_cc_report 12:40:03 INFO - PID 1677 | __startTimestamp1497987603807__endTimestamp 12:40:03 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | ------- Summary: start ------- 12:40:03 INFO - PID 1677 | Number of tests: 14 12:40:03 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | [#0] ai-astar Cycles:10 Average:121.10 Median:120.50 stddev:23.20 (19.3%) stddev-sans-first:24.56 12:40:03 INFO - PID 1677 | Values: 125.0 106.0 110.0 168.0 157.0 116.0 115.0 110.0 108.0 96.0 12:40:03 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:139.50 stddev:14.32 (10.3%) stddev-sans-first:14.88 12:40:03 INFO - PID 1677 | Values: 138.0 115.0 141.0 142.0 111.0 123.0 114.0 147.0 146.0 121.0 12:40:03 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | [#2] audio-dft Cycles:10 Average:197.00 Median:199.00 stddev:3.77 (1.9%) stddev-sans-first:3.93 12:40:03 INFO - PID 1677 | Values: 199.0 202.0 201.0 199.0 196.0 190.0 193.0 197.0 199.0 194.0 12:40:03 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | [#3] audio-fft Cycles:10 Average:79.60 Median:62.50 stddev:31.08 (49.7%) stddev-sans-first:32.88 12:40:03 INFO - PID 1677 | Values: 73.0 73.0 81.0 148.0 59.0 56.0 60.0 65.0 123.0 58.0 12:40:03 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | [#4] audio-oscillator Cycles:10 Average:84.40 Median:69.50 stddev:23.81 (34.3%) stddev-sans-first:24.59 12:40:03 INFO - PID 1677 | Values: 69.0 70.0 113.0 70.0 70.0 69.0 106.0 67.0 77.0 133.0 12:40:03 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:91.40 Median:89.00 stddev:6.29 (7.1%) stddev-sans-first:6.61 12:40:03 INFO - PID 1677 | Values: 89.0 96.0 90.0 88.0 88.0 89.0 89.0 89.0 108.0 88.0 12:40:03 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | [#6] imaging-darkroom Cycles:10 Average:96.30 Median:96.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 12:40:03 INFO - PID 1677 | Values: 96.0 98.0 96.0 96.0 97.0 96.0 96.0 97.0 95.0 96.0 12:40:03 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | [#7] imaging-desaturate Cycles:10 Average:103.80 Median:91.00 stddev:20.42 (22.4%) stddev-sans-first:21.21 12:40:03 INFO - PID 1677 | Values: 92.0 95.0 91.0 90.0 93.0 135.0 143.0 91.0 90.0 118.0 12:40:03 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:52.50 stddev:2.75 (5.2%) stddev-sans-first:2.74 12:40:03 INFO - PID 1677 | Values: 48.0 47.0 46.0 53.0 50.0 52.0 53.0 53.0 52.0 53.0 12:40:03 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:55.80 Median:56.00 stddev:1.23 (2.2%) stddev-sans-first:1.12 12:40:03 INFO - PID 1677 | Values: 54.0 56.0 55.0 55.0 58.0 55.0 57.0 56.0 57.0 55.0 12:40:03 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:79.00 stddev:9.43 (11.9%) stddev-sans-first:9.82 12:40:03 INFO - PID 1677 | Values: 79.0 81.0 103.0 100.0 85.0 79.0 79.0 79.0 79.0 77.0 12:40:03 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:140.30 Median:138.00 stddev:9.56 (6.9%) stddev-sans-first:9.86 12:40:03 INFO - PID 1677 | Values: 134.0 136.0 133.0 133.0 131.0 137.0 151.0 150.0 139.0 159.0 12:40:03 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:200.00 Median:207.00 stddev:23.21 (11.2%) stddev-sans-first:24.52 12:40:03 INFO - PID 1677 | Values: 194.0 220.0 175.0 222.0 224.0 183.0 182.0 187.0 237.0 176.0 12:40:03 INFO - PID 1677 | 12:40:03 INFO - PID 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.00 stddev:1.34 (2.1%) stddev-sans-first:1.33 12:40:03 INFO - PID 1677 | Values: 63.0 67.0 64.0 63.0 65.0 64.0 64.0 63.0 64.0 66.0 12:40:03 INFO - PID 1677 | -------- Summary: end -------- 12:40:03 INFO - PID 1677 | 12:40:04 INFO - TEST-INFO | 1677: exit 0 12:40:04 INFO - TEST-OK | kraken | took 89242ms 12:40:04 INFO - SUITE-END | took 1235s 12:40:05 INFO - Completed test suite (00:20:36) 12:40:05 INFO - javascript benchmark 12:40:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8386.020766686052, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3584.8028358440274, 3604.243108947121, 3648.9748189754678, 3693.021349342425, 3700.352438323293, 6278.9639709448, 6284.151639528663, 6301.590396624222, 6321.051842223669, 6323.462505687022, 6314.463270622003, 6372.139761132245, 6386.169797926274, 6394.88089584322, 6411.775587854426, 5540.695261760601, 5562.276904002494, 5570.777168913249, 5664.291963504562, 5703.0589952657765, 5577.05190117681, 5585.553155747551, 5682.829515114553, 5689.175069614904, 5689.175069614904, 5460.426655201187, 5475.6440831346235, 5540.50135487804, 5546.057170281062, 5595.860103497404, 5525.4474552544825, 5534.529564986981, 5555.777768889239, 5614.185943038277, 5638.802641907555, 4782.856514304576, 5488.396276409591, 5548.55611551075, 5583.078791999315, 5592.5805564582415, 6063.120847477091, 6314.463270622003, 6361.650109243989, 6365.777197798101, 6367.808965731036, 6655.367740064735, 6916.23921368649, 6916.723331066752, 6934.063901373351, 6939.757108501229, 5462.8361149165985, 6463.5798673086565, 6677.232118306409, 6835.043093966849, 6977.33715297046, 5965.433283838029, 6364.268109167505, 6380.457661098766, 6446.77436289737, 6477.190351206099, 6934.098567186234, 7142.035825163536, 7159.0, 7181.389581885598, 7186.0, 6639.734410623618, 6647.700853461606, 6655.500837437212, 6657.167854018247, 6658.201015878029, 6841.863162736718, 6846.110005699227, 6854.760083397057, 6976.127984002, 7033.788986330418, 6860.6912688929115, 6863.485238607024, 6874.587524748531, 6891.345322194384, 6912.516123871281, 6887.311268873073, 6889.621070841052, 6889.896651550231, 6910.585364878023, 6910.827229319207, 5358.4373640767935, 5375.838724838183, 5426.9185962210595, 5444.6188766785945, 5454.672719636835, 4647.7211367317395, 4858.076965376567, 4917.704937703749, 4927.162382395001, 4967.776450059756, 6327.683615819283, 6393.104965304861, 6439.0, 6441.967790161019, 6460.903086453707, 5904.937111320004, 7024.2273349931465, 7029.718811247493, 7036.085308909809, 7039.190493093258, 6390.9360906391275, 6438.935610643927, 6440.13058237132, 6441.742330306829, 6444.7744328948265, 5905.822825315247, 5992.550558708202, 5997.850053748604, 6025.0359942409, 6133.570650054543, 7016.859662806613, 7073.893891591733, 7074.327938846007, 7087.043249161504, 7150.606716630636, 6979.4765392594745, 7029.05107810439, 7048.154221493453, 7049.224585295511, 7071.929280707127, 6282.528810339334, 6827.453803695792, 7174.784756457315, 7201.459890508128, 7201.927980720126, 6883.690233939385, 6908.965455172692, 6919.135108111486, 6931.272216417406, 6949.166100068024, 6781.830454238683, 6833.692483838338, 6881.621510816753, 6883.208431030495, 7046.506744527834, 6637.70130344126, 6667.599944003376, 6756.189257289157, 6771.695273712597, 6776.93223067763, 6670.366315200048, 6672.966635166793, 6728.798136055926, 6760.93239067603, 6773.254941956479, 6443.581167224115, 6451.870962580629, 6616.371444712745, 6646.60120392778, 6669.599824010575, 6577.408033277028, 6582.572132811352, 6595.208574971034, 6597.373249541286, 6627.5360724750735, 6478.70845811949, 6565.048068030204, 6585.539012269095, 6606.306337834651, 6635.767748128792], "value": 6266.048411860137, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.8021914302279, 822.2763967708415, 844.8479273730728, 862.7368652560971, 867.7570280321512, 37661.81169094154, 38458.0, 38467.80766096173, 38468.6535971241, 38649.61350386495, 37953.810230948875, 37982.81008594953, 38196.42705359422, 38265.80867095668, 38374.4243836342, 6372.872542549147, 7549.018627578407, 7575.621218939031, 7585.279398457165, 7586.962065189667, 7461.552306861606, 7526.285002924741, 7567.432442566802, 7572.091349038124, 7577.393808495307, 7519.548827070366, 7544.0947086349715, 7550.471466997313, 7555.16893141754, 7560.281773231562, 7506.399488040971, 7519.962400187964, 7525.849483010309, 7533.359664428557, 7567.470277080606, 6881.518293719441, 8109.351251899834, 8273.420860539765, 8286.792830179294, 8306.252437280673, 36563.268734625155, 36750.63249367499, 36894.44658330127, 36904.81547592245, 36928.81535592332, 3528.5942116656656, 3575.302815950891, 3579.49887015818, 3581.3195492856275, 3583.7133029357587, 30169.0, 30525.0, 30537.69462305393, 30564.0, 30666.386672266653, 3046.588710524073, 3137.137287246003, 3148.2916343822685, 3164.6360668523184, 3173.825439600821, 267957.0, 269790.6510467455, 273555.0, 330514.0, 334094.0, 48177.0, 48767.5123248763, 48795.756021220375, 48805.755971219914, 48923.0, 50864.23703644448, 50901.490985090415, 50979.0, 51028.23457648138, 51089.978200436526, 15404.0, 15511.224438778241, 15549.611259718371, 15559.922200389152, 15615.687686246212, 12844.908182804566, 12860.871391285968, 12864.099513033998, 12874.0, 12882.677933051562, 13639.181649101085, 13670.794938075936, 13699.246541440316, 13720.725585488235, 13792.310384480936, 5774.855628609318, 5975.163477113208, 6003.249593800775, 6023.969880150659, 6040.939590604125, 1007.8538611901233, 1008.3848852200152, 1019.673704414595, 1069.8288273876142, 1080.7460246841986, 18376.632467350846, 18662.813371866378, 20412.591748164952, 22687.773122268896, 23502.764972350058, 1053.5469748008277, 1076.7200527862656, 1090.6019302954364, 1124.746931940307, 1150.2006105756418, 11607.6517704469, 11940.641780746591, 11957.34234617087, 12309.199902006341, 12340.691482713004, 343985.0, 348858.0, 349370.0, 350169.0, 350270.0, 53605.46394536005, 54026.45973540293, 54042.729786350814, 54071.0, 54146.18780718293, 1507.5100592307545, 1540.6148462884055, 1556.0430335343908, 1561.3832536148159, 1571.088768514259, 22086.0, 22478.775212247667, 22881.542369152856, 23092.076316947136, 24217.0, 37650.246995060494, 37825.81087094547, 37831.81084094562, 37833.810830945666, 37851.24297514089, 270331.0, 271712.2828771687, 272855.0, 272895.0, 273477.0, 33958.0, 34545.48181777225, 35852.462213067345, 35935.82032089823, 36647.0, 42191.57808421877, 42558.787206063775, 44234.0, 44423.55576444194, 44494.11011779811, 27279.86360068187, 28798.280042999093, 30622.387552249274, 31339.686603133676, 31597.368052639278, 28799.712002879704, 29284.41431171322, 30496.0, 30674.539881902234, 30992.69007309898, 30017.699823002364, 37242.25515489729, 37273.81363093276, 37332.06669833167, 37363.252734945694, 26906.32734181661, 29608.703912960595, 29648.703512965458, 31233.219169520944, 31553.21116972094, 22987.425314366606, 24303.392415189763, 24450.51098978046, 24498.142565010137, 24517.632235516125], "value": 17230.83810342412, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.2140888942135, 508.85507607882266, 513.3531749995008, 515.5437437867487, 533.1416419564503, 18805.71791423128, 21866.781332186674, 22015.669764953538, 22330.665040024414, 22817.08731650734, 24010.51978960424, 24508.897099630514, 24736.75263247367, 24764.0, 24829.62755558668, 6374.745010199586, 6594.6043237405665, 6600.735970561172, 6608.2730899601, 6616.966915165417, 6448.355164483561, 6548.1814773153355, 6555.0495178199135, 6583.0, 6599.604023758566, 5870.501007414375, 6450.4194622483965, 6453.580517266386, 6478.967605161967, 6506.0891475193275, 6382.20222472191, 6417.871642567123, 6440.80677579671, 6444.7744328948265, 6454.93545064548, 5009.2486127080865, 5869.236999190078, 5872.970635146797, 5874.941250587525, 5882.764689412418, 27794.6646402431, 28188.71811281902, 28627.57058644122, 28792.280192995342, 28914.27714307138, 2293.2317673579373, 2305.9077636894503, 2325.395397196724, 2338.076459798387, 2341.519988402373, 18137.909310453364, 18544.980026098558, 18596.07019649012, 18638.90680546602, 18644.0, 2027.432318950696, 2128.3508529898377, 2172.6089303925287, 2202.548477562112, 2265.6374980003284, 135509.0, 172645.99999999875, 174206.0, 174790.0, 178938.0, 13593.86406135926, 13664.590062298146, 14857.702845943237, 15347.462838800588, 15371.231438428034, 19841.702374464396, 20082.598348032956, 20120.496987575134, 20142.496437588885, 20151.395458136278, 10129.139023183112, 10209.438480883475, 10224.64213752515, 10301.74245643865, 10309.226807989431, 8714.38999270045, 8968.417052891542, 9521.523923803783, 9667.903320966841, 9737.805243895224, 7462.179160292363, 8870.334724895658, 8973.32700047499, 9049.230815380766, 9057.184853363096, 2662.4142688608476, 2678.39736059389, 2680.2629276948605, 2683.9731602684114, 2693.7575618194455, 993.9403635781877, 996.5515518016921, 1013.7668336282696, 1062.8733542445034, 1073.2380010192694, 17076.487705368858, 21323.0, 21424.98721165329, 22171.00230489632, 22490.437739056328, 892.238521775105, 1016.0601443664581, 1046.1160319529986, 1058.7459009837737, 1080.9243352965218, 10862.456877156268, 11140.144412973148, 11210.663680089447, 11212.943935280271, 11456.713582160346, 484334.0, 569858.0, 613524.0, 619357.0, 628408.0, 162620.18689906475, 169058.0, 173978.0, 174706.0, 176160.0, 1215.2040413529153, 1256.4094875408543, 1259.8740125987329, 1264.8545417276946, 1267.1700036476307, 21826.454338641026, 22891.08435662555, 23538.646920295865, 23593.0, 23783.167589134297, 66756.0, 71459.0, 73524.0, 75460.62269688616, 75935.0, 100114.4994275024, 100955.0, 102293.97706022844, 104546.0, 105126.0, 10817.242793004414, 11684.474198660911, 11789.351585662527, 11806.1145414096, 11977.0, 18399.0, 18445.354412595494, 18601.627967440847, 18694.34569790051, 18902.164675298816, 14272.500462483764, 14576.562703118923, 14822.925885370503, 15176.24118794078, 15196.468123615621, 13136.0, 13226.470941162246, 13666.316684165951, 13854.445822167001, 14044.368003439666, 15013.474528391454, 15598.610034749223, 16129.11289879068, 16130.274137663599, 16138.596535086717, 11902.464389102339, 11916.76166476683, 11943.940280298542, 11962.10284228669, 11975.221610595283, 13970.930145349208, 14947.551573452814, 15067.4726384576, 15073.944823862224, 15088.471903483325], "value": 11883.872602768522, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1283.274949653446, 1459.9342479989607, 1460.8100946876898, 1462.7732701431282, 1465.8973871828973, 11850.3482308473, 13872.0, 13887.722245555084, 13892.583222503341, 13920.860791392095, 13319.467221311135, 13688.589342319747, 13692.041557091008, 13705.0, 13742.931285343584, 2393.5571919194917, 2852.985735071322, 2858.985705071472, 2859.7426231639147, 2863.2841789552613, 2296.5062511560095, 2819.7321254480894, 2821.858907054639, 2823.8023338366324, 2825.1242114944434, 2661.72051934546, 2778.569321755131, 2824.8305101693963, 2830.037787152368, 2847.9857600711966, 2821.2523681224493, 2843.2323272716458, 2843.360243945106, 2843.8862445502364, 2851.17315978366, 2773.2234974207254, 2793.050362876632, 2797.776177905783, 2797.9088155619324, 2808.8455134967567, 13191.736165276736, 13457.058005939589, 13486.527971521047, 13490.730185396236, 13509.729805403935, 7356.595387253699, 7606.65770040344, 7643.770686879402, 7651.311381975594, 7667.463277570572, 4542.454905411372, 4684.227102528079, 4712.175369310357, 4779.569838714532, 4782.808687652489, 1373.6703191234033, 1376.43566137884, 1377.8759911607904, 1394.8256467941508, 1400.4188261871311, 20379.4905127373, 20554.5889082219, 20607.793922060737, 20627.48431289215, 20640.0, 5715.657060576379, 6531.477481801445, 6715.496337774686, 6721.529492935448, 6804.591724496546, 13283.468661253635, 13392.933035334761, 13421.39603717835, 13442.932785336207, 13457.730845383037, 7307.1231452226075, 7420.5547667140145, 7427.294407031323, 7437.776866694008, 7448.627568621656, 6569.113169722122, 6789.25318214996, 6862.759803406957, 6879.0, 6935.3411425914455, 4545.931811022771, 4939.925901111486, 5058.064258112261, 5082.491750824962, 5135.409427915837, 1493.992530037343, 1511.60698218464, 1522.0943538594518, 1531.9004264722757, 1536.9231538423257, 816.3550794872103, 828.3166387730147, 833.6040380819062, 839.3578912132224, 864.5936409887343, 6978.860422791516, 7155.427565794724, 7200.927990720131, 7246.0, 7264.839794689327, 666.3136968922018, 674.8987651852163, 694.1150033707064, 708.19619731605, 709.7480394459996, 3625.782453052825, 4762.857114286577, 5075.314832497639, 5392.91910621341, 5471.452854714496, 18572.978486183118, 18638.813611863978, 18864.15111319994, 18894.905525472284, 18914.43256702301, 18180.363687271147, 18366.265349385874, 18515.259389624265, 18969.051547422572, 19001.239950402105, 9282.953585232031, 9302.488363140092, 9563.760905977268, 9633.662821801203, 9643.517824108767, 7915.168907264884, 7939.84120317602, 8186.140455252113, 8415.663373464993, 8587.957060214909, 16596.58508537296, 16727.91636041812, 16971.575710607332, 17261.56846078858, 17283.7407438886, 17399.56501087483, 17604.91197544004, 17736.467905962843, 17793.199306031518, 17912.820871791115, 8650.43772154808, 9400.529973501161, 10319.690409287734, 10340.741481463023, 10367.896321036693, 12473.563425280072, 12595.433205505591, 12616.36918154107, 12618.49526018949, 12653.430595623035, 8989.46063236208, 9822.803543929223, 9998.200143988319, 10292.485375731334, 10449.582016719247, 9497.0, 9695.660651877433, 10125.189984801345, 10206.642767503101, 10394.064534191657, 9171.63313467454, 9184.816303674022, 9208.309376796633, 9308.208802251884, 9326.347155699035, 6385.9460252991485, 6501.2523559791225, 6938.8612227756175, 6996.965015174892, 7006.684699188447, 6712.328767123444, 6938.653067346713, 7009.264027277266, 7147.892781608176, 7184.497085203986], "value": 6087.300684648093, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.92026054919296, 439.5670264789184, 467.9321498382736, 477.2149813556699, 480.98557043288713, 6363.681815909209, 6468.741250349986, 6590.538662293641, 6595.142631457916, 6614.702338394772, 5649.971750141243, 6203.782867599635, 6349.936500634993, 6354.650494222816, 6429.517786166033, 4137.8758637240935, 4808.158572249842, 4815.133276010317, 4831.227003679412, 4837.201861692825, 3557.2351944331963, 4804.183288840905, 4812.855614331575, 4830.9033819323595, 4861.659683822116, 3720.330340538702, 4231.576842315759, 4299.785010749466, 4495.797689103998, 4808.543188397097, 3768.5854555998817, 4387.990762124713, 4608.262677971542, 4766.713997160165, 4798.184308667535, 4059.147579008412, 4222.725522841006, 4234.195502854448, 4240.321548552248, 4244.575542445731, 5968.224130863004, 6316.30520642729, 6324.272708638474, 6324.747010119636, 6344.873102537924, 5716.857078573027, 5748.7987920423, 5749.511291540224, 5753.424657534255, 5767.5962682612235, 6957.478189135835, 6970.93029069713, 6981.441484681213, 6990.0, 7007.649617519103, 4173.520045194811, 4848.878778030542, 4865.464798872121, 4868.221084626478, 4889.3643826302705, 6708.899366509458, 6803.353681400308, 6947.756828511028, 7062.752803651848, 7327.702533859731, 4939.185034469344, 4946.463278755707, 4948.183549714328, 4948.331975183376, 4955.331030310934, 5011.899762004812, 5029.597632189416, 5038.395392552917, 5046.84859454217, 5078.593712503065, 5034.647574669812, 5060.671056381323, 5497.230387745719, 5545.195946587803, 5766.048601980626, 6582.605043697297, 6918.93081069193, 6981.581105133709, 7018.33325834045, 7033.869927882146, 1582.3512359932372, 1693.865110376051, 1698.9660206795795, 1704.2927185218243, 1706.3089448773267, 1062.5431064642123, 1111.5276007696696, 1145.7364806094645, 1157.9189456737947, 1170.9941450292695, 5504.862378440491, 5707.686077265707, 6192.659403732838, 6344.524160687967, 6450.903236451457, 6731.427828634523, 7034.331738484836, 7098.787036388917, 7104.3961263293195, 7199.1361036675935, 5926.496247818897, 5940.584159108907, 6378.170837791057, 6383.2978372379, 6420.367649263872, 3401.6598340165783, 3728.8881333560034, 3730.0861288984374, 3738.5700644425697, 3779.829907654107, 6239.469645080219, 7192.065031545865, 7334.17634210822, 7364.784810506314, 7384.2246564111065, 6316.399942005594, 6369.490440764727, 6601.108850305243, 6897.48268879836, 7349.669264883093, 7301.598412087386, 7328.30381113804, 7330.853382932311, 7337.229590892986, 7399.852002960019, 5578.051731205786, 5913.66942437948, 5951.404859514014, 6929.549579277331, 7118.359347658736, 5647.3787883333625, 6673.265940746611, 7044.894326585208, 7059.399951004017, 7185.892211616725, 5753.4246575343805, 6700.798976030727, 6939.514234003571, 7259.6007219603425, 7386.269933683456, 5086.3896332440345, 5494.2033405155225, 5511.118221084607, 5525.955396503319, 5556.499915007668, 7175.138983322035, 7176.96411517939, 7198.4601154914635, 7201.531900426456, 7215.530990485601, 6825.863482730417, 6895.069165662772, 6896.931030689629, 6908.205556360882, 6952.791416257521, 6014.609050411709, 6065.332813390612, 6632.568883022588, 6899.517033807585, 6913.654317284217, 6245.6252624842655, 6714.261431242462, 6728.058071869844, 6742.4943129266485, 6783.898241526283, 6853.246142924374, 6860.108185935996, 6866.210385805696, 6906.965465172642, 6916.93083069183, 5800.912986305125, 6157.692115394123, 6641.468682505291, 6679.666016699049, 6685.465162786868], "value": 5065.57142609012, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.3929579505316, 805.8186907945712, 846.7629063862117, 869.7043005378171, 875.5228400521715, 16627.251773670185, 16987.0, 17172.055536945463, 17208.48374548764, 17211.3115475381, 16881.493555193363, 18520.073996300198, 18540.351087711933, 18570.35003774865, 18570.907145464254, 1003.7189586915675, 1021.8824835143979, 1032.2052019944638, 1033.018632299315, 1034.260503739826, 1041.7916416716648, 1042.1662669864115, 1042.2599954032646, 1045.7385653586603, 1053.1995683280722, 1039.428314427066, 1073.2380010192774, 1083.3770581915392, 1092.748667806405, 1092.9781404371909, 1068.2895874243618, 1077.5097330714507, 1077.8437126616636, 1082.3830416662506, 1086.4296244471636, 789.5973053742575, 789.996704185681, 792.7621713485988, 793.5635400529743, 799.2367289238821, 14304.57086287413, 16521.00873947555, 16536.090515021668, 16573.83426165735, 17021.574460638458, 17440.738388924176, 17598.736018959728, 17640.294388224454, 17851.0, 18070.457886263433, 14680.04579702316, 14692.412303507846, 14850.925745371313, 14857.405703771836, 14879.03286286388, 3334.3498017886277, 3453.0849324928913, 3577.1951310955087, 3589.210373717778, 3597.7661452005536, 133376.66623333836, 136186.0, 136785.31607342, 138124.0, 143146.0, 14036.29818509091, 14210.786838197435, 14229.003969722227, 14253.714925701635, 14394.712105758035, 26907.0, 27039.864800676263, 27312.453750925266, 27366.726332736416, 27435.451290974466, 7992.960035199787, 8204.0, 8215.137410571804, 8318.958405207935, 8333.250007499355, 4043.534993475729, 4129.236091323055, 4131.35963925592, 4134.3178375567695, 4134.400511925754, 4595.655325850575, 4671.112488627218, 4714.0, 4998.9500104999215, 5207.270982062514, 843.5149788871423, 1027.773889744255, 1047.2878442658912, 1051.8106740786732, 1053.8208504554243, 578.5602941764289, 626.1671976271548, 635.5138319185827, 660.7654282729658, 679.341039191983, 10740.174170391052, 11297.661070167907, 11459.427028648533, 11668.766624667629, 12054.457549410297, 620.4106099205773, 628.6542401679059, 645.4545908707229, 662.3211208511218, 668.4318329419954, 4968.27959945813, 5400.810971615974, 5620.494155526022, 5623.184638227516, 5873.588848780544, 107032.0, 145000.0, 151468.0, 151848.0, 154197.2290138542, 127203.72796272104, 127608.0, 127862.36068819596, 131756.0, 131848.0, 101718.0, 115945.0, 116344.0, 118702.81297187091, 118944.0, 9039.644053391781, 10141.746456338651, 10692.358458492516, 10779.245452818228, 11262.0, 45248.32127518024, 45372.0, 45816.0, 47653.76173119112, 48262.75868620635, 63637.68181159064, 65354.67322663547, 68666.62666746737, 68937.0, 69178.65410672914, 14328.570142895729, 14331.426742930584, 14341.85658143447, 14659.120452772868, 14686.485972990895, 14957.626059348604, 15437.614059648598, 15861.682766344838, 15876.285567149274, 16026.198690065683, 15010.399584016517, 17941.372051978116, 18351.632967340312, 18366.449006529827, 18425.0, 16905.070221137954, 17265.482035538953, 17290.567735806704, 17467.30130794754, 17473.301067957138, 15552.533423997298, 16253.943493672874, 16498.34006639721, 16640.83359166393, 17038.233279502205, 9671.081247281498, 10290.485475726333, 10629.681109566725, 12024.458899349374, 12382.81425778632, 12884.484620615445, 13028.0, 13441.193528388329, 13465.259410732504, 13980.860191397956], "value": 8790.173711219879, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1498.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 106.0, 110.0, 168.0, 157.0, 116.0, 115.0, 110.0, 108.0, 96.0], "value": 121.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 115.0, 141.0, 142.0, 111.0, 123.0, 114.0, 147.0, 146.0, 121.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 202.0, 201.0, 199.0, 196.0, 190.0, 193.0, 197.0, 199.0, 194.0], "value": 197.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 81.0, 148.0, 59.0, 56.0, 60.0, 65.0, 123.0, 58.0], "value": 79.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 113.0, 70.0, 70.0, 69.0, 106.0, 67.0, 77.0, 133.0], "value": 84.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 96.0, 90.0, 88.0, 88.0, 89.0, 89.0, 89.0, 108.0, 88.0], "value": 91.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 96.0, 96.0, 97.0, 96.0, 96.0, 97.0, 95.0, 96.0], "value": 96.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 91.0, 90.0, 93.0, 135.0, 143.0, 91.0, 90.0, 118.0], "value": 103.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 47.0, 46.0, 53.0, 50.0, 52.0, 53.0, 53.0, 52.0, 53.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 55.0, 55.0, 58.0, 55.0, 57.0, 56.0, 57.0, 55.0], "value": 55.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 103.0, 100.0, 85.0, 79.0, 79.0, 79.0, 79.0, 77.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 136.0, 133.0, 133.0, 131.0, 137.0, 151.0, 150.0, 139.0, 159.0], "value": 140.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 220.0, 175.0, 222.0, 224.0, 183.0, 182.0, 187.0, 237.0, 176.0], "value": 200.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 64.0, 63.0, 65.0, 64.0, 64.0, 63.0, 64.0, 66.0], "value": 64.3, "unit": "score"}]}]} 12:40:05 INFO - Return code: 0 12:40:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:40:05 INFO - # TBPL SUCCESS # 12:40:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:40:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:40:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:40:05 INFO - 2017-06-20 12:40:05.218 screenresolution[1685:11142] starting screenresolution argv=screenresolution get 12:40:05 INFO - 2017-06-20 12:40:05.221 screenresolution[1685:11142] Display 0: 1600x1200x32@60 12:40:05 INFO - 2017-06-20 12:40:05.227 screenresolution[1686:11145] starting screenresolution argv=screenresolution list 12:40:05 INFO - Available Modes on Display 0 12:40:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:40:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:40:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:40:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:40:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:40:05 INFO - Intel Iris: 12:40:05 INFO - Chipset Model: Intel Iris 12:40:05 INFO - Type: GPU 12:40:05 INFO - Bus: Built-In 12:40:05 INFO - VRAM (Dynamic, Max): 1536 MB 12:40:05 INFO - Vendor: Intel (0x8086) 12:40:05 INFO - Device ID: 0x0a2e 12:40:05 INFO - Revision ID: 0x0009 12:40:05 INFO - Displays: 12:40:05 INFO - Display: 12:40:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:40:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:40:05 INFO - Display Serial Number: 1600x1200 60 12:40:05 INFO - Main Display: Yes 12:40:05 INFO - Mirror: Off 12:40:05 INFO - Online: Yes 12:40:05 INFO - Rotation: Supported 12:40:05 INFO - Return code: 0 12:40:05 INFO - Running post-action listener: _resource_record_post_action 12:40:05 INFO - [mozharness: 2017-06-20 19:40:05.466093Z] Finished run-tests step (success) 12:40:05 INFO - Running post-run listener: _resource_record_post_run 12:40:05 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 39449600; Write bytes: 560627712; Read time: 290; Write time: 1686 12:40:05 INFO - TinderboxPrint: CPU usage
53.3% 12:40:05 INFO - TinderboxPrint: I/O read bytes / time
39,449,600 / 290 12:40:05 INFO - TinderboxPrint: I/O write bytes / time
560,627,712 / 1,686 12:40:05 INFO - TinderboxPrint: CPU idle
2,351.0 (46.7%) 12:40:05 INFO - TinderboxPrint: CPU system
1,020.6 (20.3%) 12:40:05 INFO - TinderboxPrint: CPU user
1,658.2 (33.0%) 12:40:05 INFO - TinderboxPrint: Swap in / out
295,911,424 / 0 12:40:05 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 238346752; Write bytes: 233576448; Read time: 17047; Write time: 476 12:40:05 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:40:05 INFO - run-tests - Wall time: 1237s; CPU: 53.0%; Read bytes: 37274624; Write bytes: 312657920; Read time: 267; Write time: 1168 12:40:05 INFO - Running post-run listener: _upload_blobber_files 12:40:05 INFO - Blob upload gear active. 12:40:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:40:05 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 12:40:05 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'] 12:40:05 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 12:40:06 INFO - (blobuploader) - INFO - Open directory for files ... 12:40:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:40:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:40:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:40:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:40:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:40:07 INFO - (blobuploader) - INFO - Done attempting. 12:40:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:40:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:40:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:40:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:40:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:40:08 INFO - (blobuploader) - INFO - Done attempting. 12:40:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:40:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:40:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:40:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:40:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:40:09 INFO - (blobuploader) - INFO - Done attempting. 12:40:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:40:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:40:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:40:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:40:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:40:11 INFO - (blobuploader) - INFO - Done attempting. 12:40:11 INFO - (blobuploader) - INFO - Iteration through files over. 12:40:11 INFO - Return code: 0 12:40:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:40:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:40:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b13464313bb490259976171dc0d580081352914849c4cb5129882b2aa8b524ea38d20a8454658e547a1b81575f15a1721263348b59419390db333817dabe8d37", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bf838ebc369954f054a94b06aba4121576668664dd2ce59fd2fdb646128daa381c5c5e92aacc67798b8d9eb853c53a29c2baa8aa8c8627f52abf0632b31e3382", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d3ef7c31b4fa29a97c38dabea9a35c82a94c9c5341df2385013cbb69ee44472b4782f7879a23055c28a17bd7c59df8b6394093b0cdec7900605c104f4be24cc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e986ff9f5ec5ec924b893a4ae37b6748776cac3d6ef75e833f82cc8556542ca71d97042ee21cdace07673f5e00dc9c84ea02a07ce728b071f9ac3caad7454aa"} 12:40:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:40:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:40:11 INFO - Contents: 12:40:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b13464313bb490259976171dc0d580081352914849c4cb5129882b2aa8b524ea38d20a8454658e547a1b81575f15a1721263348b59419390db333817dabe8d37", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bf838ebc369954f054a94b06aba4121576668664dd2ce59fd2fdb646128daa381c5c5e92aacc67798b8d9eb853c53a29c2baa8aa8c8627f52abf0632b31e3382", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d3ef7c31b4fa29a97c38dabea9a35c82a94c9c5341df2385013cbb69ee44472b4782f7879a23055c28a17bd7c59df8b6394093b0cdec7900605c104f4be24cc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e986ff9f5ec5ec924b893a4ae37b6748776cac3d6ef75e833f82cc8556542ca71d97042ee21cdace07673f5e00dc9c84ea02a07ce728b071f9ac3caad7454aa"} 12:40:11 INFO - Running post-run listener: copy_logs_to_upload_dir 12:40:11 INFO - Copying logs to upload dir... 12:40:11 INFO - mkdir: /builds/slave/test/build/upload/logs 12:40:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.361661 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-20 12:40:11.504218) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-20 12:40:11.510000) ========= 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.Ez7hcIS3W7/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.JeGU6h04Ow/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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/b13464313bb490259976171dc0d580081352914849c4cb5129882b2aa8b524ea38d20a8454658e547a1b81575f15a1721263348b59419390db333817dabe8d37", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bf838ebc369954f054a94b06aba4121576668664dd2ce59fd2fdb646128daa381c5c5e92aacc67798b8d9eb853c53a29c2baa8aa8c8627f52abf0632b31e3382", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d3ef7c31b4fa29a97c38dabea9a35c82a94c9c5341df2385013cbb69ee44472b4782f7879a23055c28a17bd7c59df8b6394093b0cdec7900605c104f4be24cc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e986ff9f5ec5ec924b893a4ae37b6748776cac3d6ef75e833f82cc8556542ca71d97042ee21cdace07673f5e00dc9c84ea02a07ce728b071f9ac3caad7454aa"} build_url:https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011159 build_url: 'https://queue.taskcluster.net/v1/task/YEMTBEiFQOGiLF9zC9_dfA/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/b13464313bb490259976171dc0d580081352914849c4cb5129882b2aa8b524ea38d20a8454658e547a1b81575f15a1721263348b59419390db333817dabe8d37", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bf838ebc369954f054a94b06aba4121576668664dd2ce59fd2fdb646128daa381c5c5e92aacc67798b8d9eb853c53a29c2baa8aa8c8627f52abf0632b31e3382", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d3ef7c31b4fa29a97c38dabea9a35c82a94c9c5341df2385013cbb69ee44472b4782f7879a23055c28a17bd7c59df8b6394093b0cdec7900605c104f4be24cc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e986ff9f5ec5ec924b893a4ae37b6748776cac3d6ef75e833f82cc8556542ca71d97042ee21cdace07673f5e00dc9c84ea02a07ce728b071f9ac3caad7454aa"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-20 12:40:11.543429) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 12:40:11.543805) ========= 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.Ez7hcIS3W7/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.JeGU6h04Ow/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.005148 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 12:40:11.592764) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-20 12:40:11.593203) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-20 12:40:11.593542) ========= ========= Total master_lag: 0.18 =========