builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0167 starttime: 1497373600.14 results: success (0) buildid: 20170613090034 builduid: 2d30efa81a5a4abda5b0be07b4d4eab8 revision: 02caa113457b2780c13bf0d7c26cb739558b5f5b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 10:06:40.145169) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 10:06:40.145659) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 10:06:40.145951) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 10:06:40.170882) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:06:40.171229) ========= 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.8Ethf33D2z/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.FrRfVDOE8T/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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-13 10:06:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.56M=0.001s 2017-06-13 10:06:40 (8.56 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113670 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:06:40.307397) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:06:40.307709) ========= 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.8Ethf33D2z/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.FrRfVDOE8T/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.037364 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:06:40.365823) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 10:06:40.366175) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 02caa113457b2780c13bf0d7c26cb739558b5f5b --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 02caa113457b2780c13bf0d7c26cb739558b5f5b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8Ethf33D2z/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.FrRfVDOE8T/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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-13 10:06:40,512 truncating revision to first 12 chars 2017-06-13 10:06:40,512 Setting DEBUG logging. 2017-06-13 10:06:40,512 attempt 1/10 2017-06-13 10:06:40,513 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/02caa113457b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-13 10:06:42,051 unpacking tar archive at: mozilla-beta-02caa113457b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.815846 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 10:06:42.204799) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 10:06:42.205122) ========= script_repo_revision: 02caa113457b2780c13bf0d7c26cb739558b5f5b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 10:06:42.205497) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 10:06:42.205762) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 10:06:42.224205) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-13 10:06:42.224579) ========= /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.8Ethf33D2z/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.FrRfVDOE8T/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 10:06:42 INFO - MultiFileLogger online at 20170613 10:06:42 in /builds/slave/test 10:06:42 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 10:06:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:06:42 INFO - {'append_to_log': False, 10:06:42 INFO - 'base_work_dir': '/builds/slave/test', 10:06:42 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:06:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:06:42 INFO - 'branch': 'Mozilla-Beta', 10:06:42 INFO - 'buildbot_json_path': 'buildprops.json', 10:06:42 INFO - 'config_files': ('talos/mac_config.py',), 10:06:42 INFO - 'default_actions': ('clobber', 10:06:42 INFO - 'read-buildbot-config', 10:06:42 INFO - 'download-and-extract', 10:06:42 INFO - 'populate-webroot', 10:06:42 INFO - 'create-virtualenv', 10:06:42 INFO - 'install', 10:06:42 INFO - 'setup-mitmproxy', 10:06:42 INFO - 'run-tests'), 10:06:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:06:42 INFO - 'download_minidump_stackwalk': True, 10:06:42 INFO - 'download_symbols': 'ondemand', 10:06:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:06:42 INFO - 'tooltool.py': '/tools/tooltool.py', 10:06:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:06:42 INFO - '/tools/misc-python/virtualenv.py')}, 10:06:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:06:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:06:42 INFO - 'gecko_profile': False, 10:06:42 INFO - 'gecko_profile_interval': 0, 10:06:42 INFO - 'installer_path': 'installer.exe', 10:06:42 INFO - 'log_level': 'info', 10:06:42 INFO - 'log_name': 'talos', 10:06:42 INFO - 'log_to_console': True, 10:06:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:06:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:06:42 INFO - 'opt_config_files': (), 10:06:42 INFO - 'pip_index': False, 10:06:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:06:42 INFO - 'cmd': ('bash', 10:06:42 INFO - '-c', 10:06:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:06:42 INFO - 'enabled': True, 10:06:42 INFO - 'halt_on_failure': False, 10:06:42 INFO - 'name': 'check_screen_resolution'},), 10:06:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:06:42 INFO - 'cmd': ('bash', 10:06:42 INFO - '-c', 10:06:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:06:42 INFO - 'enabled': True, 10:06:42 INFO - 'halt_on_failure': False, 10:06:42 INFO - 'name': 'check_screen_resolution'},), 10:06:42 INFO - 'run_cmd_checks_enabled': True, 10:06:42 INFO - 'suite': 'dromaeojs-e10s', 10:06:42 INFO - 'system_bits': '32', 10:06:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:06:42 INFO - 'title': 't-yosemite-r7-0167', 10:06:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:06:42 INFO - 'use_talos_json': True, 10:06:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:06:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:06:42 INFO - 'work_dir': 'build'} 10:06:42 INFO - [mozharness: 2017-06-13 17:06:42.488947Z] Running clobber step. 10:06:42 INFO - Running pre-action listener: _resource_record_pre_action 10:06:42 INFO - Running main action method: clobber 10:06:42 INFO - rmtree: /builds/slave/test/build 10:06:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:06:45 INFO - Running post-action listener: _resource_record_post_action 10:06:45 INFO - [mozharness: 2017-06-13 17:06:45.046990Z] Finished clobber step (success) 10:06:45 INFO - [mozharness: 2017-06-13 17:06:45.047185Z] Running read-buildbot-config step. 10:06:45 INFO - Running pre-action listener: _resource_record_pre_action 10:06:45 INFO - Running main action method: read_buildbot_config 10:06:45 INFO - Using buildbot properties: 10:06:45 INFO - { 10:06:45 INFO - "project": "", 10:06:45 INFO - "product": "firefox", 10:06:45 INFO - "script_repo_revision": "production", 10:06:45 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 10:06:45 INFO - "repository": "", 10:06:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 10:06:45 INFO - "buildid": "20170613090034", 10:06:45 INFO - "pgo_build": "False", 10:06:45 INFO - "basedir": "/builds/slave/test", 10:06:45 INFO - "buildnumber": 13, 10:06:45 INFO - "slavename": "t-yosemite-r7-0167", 10:06:45 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 10:06:45 INFO - "platform": "yosemite_r7_devedition", 10:06:45 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 10:06:45 INFO - "branch": "mozilla-beta", 10:06:45 INFO - "repo_path": "releases/mozilla-beta", 10:06:45 INFO - "revision": "02caa113457b2780c13bf0d7c26cb739558b5f5b", 10:06:45 INFO - "stage_platform": "macosx64-devedition", 10:06:45 INFO - "builduid": "2d30efa81a5a4abda5b0be07b4d4eab8", 10:06:45 INFO - "slavebuilddir": "test" 10:06:45 INFO - } 10:06:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 10:06:45 INFO - Running post-action listener: _resource_record_post_action 10:06:45 INFO - [mozharness: 2017-06-13 17:06:45.050736Z] Finished read-buildbot-config step (success) 10:06:45 INFO - [mozharness: 2017-06-13 17:06:45.050868Z] Running download-and-extract step. 10:06:45 INFO - Running pre-action listener: _resource_record_pre_action 10:06:45 INFO - Running main action method: download_and_extract 10:06:45 INFO - mkdir: /builds/slave/test/build/tests 10:06:45 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')]} 10:06:45 INFO - trying https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 10:06:45 INFO - Downloading https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/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 10:06:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/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 10:06:46 INFO - Downloaded 1290 bytes. 10:06:46 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 10:06:46 INFO - Using the following test package requirements: 10:06:46 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:06:46 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 10:06:46 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 10:06:46 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:06:46 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 10:06:46 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:06:46 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 10:06:46 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:06:46 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:06:46 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 10:06:46 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 10:06:46 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:06:46 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 10:06:46 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:06:46 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 10:06:46 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:06:46 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 10:06:46 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 10:06:46 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 10:06:46 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 10:06:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 10:06:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 10:06:46 INFO - Fetch https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 10:06:48 INFO - Content-Length response header: 59545163 10:06:48 INFO - Bytes received: 59545163 10:07:06 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 10:07:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 10:07:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 10:07:06 INFO - Fetch https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 10:07:08 INFO - Content-Length response header: 59545163 10:07:08 INFO - Bytes received: 59545163 10:07:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 10:07:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 10:07:25 INFO - Fetch https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 10:07:26 INFO - Content-Length response header: 14450018 10:07:26 INFO - Bytes received: 14450018 10:07:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:07:26 INFO - trying https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 10:07:26 INFO - Downloading https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 10:07:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/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 10:07:28 INFO - Downloaded 66876470 bytes. 10:07:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 10:07:28 INFO - mkdir: /builds/slave/test/properties 10:07:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:07:28 INFO - Writing to file /builds/slave/test/properties/build_url 10:07:28 INFO - Contents: 10:07:28 INFO - build_url:https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 10:07:29 INFO - Running post-action listener: _resource_record_post_action 10:07:29 INFO - Running post-action listener: set_extra_try_arguments 10:07:29 INFO - [mozharness: 2017-06-13 17:07:29.451864Z] Finished download-and-extract step (success) 10:07:29 INFO - [mozharness: 2017-06-13 17:07:29.451986Z] Running populate-webroot step. 10:07:29 INFO - Running pre-action listener: _resource_record_pre_action 10:07:29 INFO - Running main action method: populate_webroot 10:07:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:07:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:07:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:07:29 INFO - '--tppagecycles', 10:07:29 INFO - '7'], 10:07:29 INFO - 'tests': ['tsvgm']}}, 10:07:29 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:07:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:07:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:07:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:07:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:07:29 INFO - 'talos_options': ['--disable-e10s'], 10:07:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:07:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:07:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:07:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:07:29 INFO - 'talos_options': ['--disable-e10s'], 10:07:29 INFO - 'tests': ['damp', 'tps']}, 10:07:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:07:29 INFO - 'tests': ['damp', 'tps']}, 10:07:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:07:29 INFO - 'tests': ['dromaeo_dom']}, 10:07:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:07:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:07:29 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:07:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:07:29 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:07:29 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:07:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:07:29 INFO - 'tests': ['a11yr', 10:07:29 INFO - 'ts_paint', 10:07:29 INFO - 'tpaint', 10:07:29 INFO - 'sessionrestore', 10:07:29 INFO - 'sessionrestore_no_auto_restore', 10:07:29 INFO - 'tabpaint']}, 10:07:29 INFO - 'other-e10s': {'tests': ['a11yr', 10:07:29 INFO - 'ts_paint', 10:07:29 INFO - 'tpaint', 10:07:29 INFO - 'sessionrestore', 10:07:29 INFO - 'sessionrestore_no_auto_restore', 10:07:29 INFO - 'tabpaint']}, 10:07:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:07:29 INFO - 'ts_paint', 10:07:29 INFO - 'tpaint', 10:07:29 INFO - 'sessionrestore', 10:07:29 INFO - 'sessionrestore_no_auto_restore', 10:07:29 INFO - 'tabpaint']}, 10:07:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:07:29 INFO - 'ts_paint', 10:07:29 INFO - 'tpaint', 10:07:29 INFO - 'sessionrestore', 10:07:29 INFO - 'sessionrestore_no_auto_restore', 10:07:29 INFO - 'tabpaint']}, 10:07:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:07:29 INFO - 'tests': ['a11yr', 10:07:29 INFO - 'ts_paint', 10:07:29 INFO - 'tpaint', 10:07:29 INFO - 'sessionrestore', 10:07:29 INFO - 'sessionrestore_no_auto_restore', 10:07:29 INFO - 'tabpaint']}, 10:07:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:07:29 INFO - 'tests': ['a11yr', 10:07:29 INFO - 'ts_paint', 10:07:29 INFO - 'tpaint', 10:07:29 INFO - 'sessionrestore', 10:07:29 INFO - 'sessionrestore_no_auto_restore', 10:07:29 INFO - 'tabpaint']}, 10:07:29 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:07:29 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:07:29 INFO - 'bloom_basic_ref']}, 10:07:29 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:07:29 INFO - 'talos_options': ['--mitmproxy', 10:07:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:07:29 INFO - '--firstNonBlankPaint'], 10:07:29 INFO - 'tests': ['quantum_pageload_google', 10:07:29 INFO - 'quantum_pageload_youtube', 10:07:29 INFO - 'quantum_pageload_amazon', 10:07:29 INFO - 'quantum_pageload_facebook']}, 10:07:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:07:29 INFO - 'tests': ['tsvgx', 10:07:29 INFO - 'tsvgr_opacity', 10:07:29 INFO - 'tart', 10:07:29 INFO - 'tscrollx', 10:07:29 INFO - 'cart', 10:07:29 INFO - 'tsvg_static']}, 10:07:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:07:29 INFO - 'tsvgr_opacity', 10:07:29 INFO - 'tart', 10:07:29 INFO - 'tscrollx', 10:07:29 INFO - 'cart', 10:07:29 INFO - 'tsvg_static']}, 10:07:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:07:29 INFO - 'talos_options': ['--disable-e10s'], 10:07:29 INFO - 'tests': ['tp5o']}, 10:07:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:07:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:07:29 INFO - 'talos_options': ['--disable-e10s', 10:07:29 INFO - '--xperf_path', 10:07:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:07:29 INFO - 'tests': ['tp5n']}, 10:07:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:07:29 INFO - 'talos_options': ['--xperf_path', 10:07:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:07:29 INFO - 'tests': ['tp5n']}}, 10:07:29 INFO - 'talos.zip': {'path': '', 10:07:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:07:29 INFO - Running post-action listener: _resource_record_post_action 10:07:29 INFO - [mozharness: 2017-06-13 17:07:29.472576Z] Finished populate-webroot step (success) 10:07:29 INFO - [mozharness: 2017-06-13 17:07:29.472677Z] Running create-virtualenv step. 10:07:29 INFO - Running pre-action listener: _resource_record_pre_action 10:07:29 INFO - Running main action method: create_virtualenv 10:07:29 INFO - Creating virtualenv /builds/slave/test/build/venv 10:07:29 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 10:07:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:07:29 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:07:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:07:29 INFO - Using real prefix '/tools/python27' 10:07:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:07:30 INFO - Installing distribute.............................................................................................................................................................................................done. 10:07:33 INFO - Installing pip.................done. 10:07:33 INFO - Return code: 0 10:07:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:07:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:07:33 INFO - Reading from file tmpfile_stdout 10:07:33 INFO - Output received: 10:07:33 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) 10:07:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:07:33 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')]} 10:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:33 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')]} 10:07:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:33 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 0x10fce6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32add69d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa3cd30>, '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 0x10fa06cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa2dbe8>, '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.8Ethf33D2z/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.FrRfVDOE8T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 10:07:33 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 10:07:33 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 10:07:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Ethf33D2z/Render', 10:07:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:07:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:07:33 INFO - 'HOME': '/Users/cltbld', 10:07:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:07:33 INFO - 'LOGNAME': 'cltbld', 10:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:33 INFO - 'MOZ_NO_REMOTE': '1', 10:07:33 INFO - 'NO_EM_RESTART': '1', 10:07:33 INFO - 'PAGER': '/bin/cat', 10:07:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:33 INFO - 'PWD': '/builds/slave/test', 10:07:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:07:33 INFO - 'SHELL': '/bin/bash', 10:07:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FrRfVDOE8T/Listeners', 10:07:33 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:07:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:07:33 INFO - 'USER': 'cltbld', 10:07:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:07:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:33 INFO - 'XPC_FLAGS': '0x0', 10:07:33 INFO - 'XPC_SERVICE_NAME': '0', 10:07:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:07:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:33 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 10:07:33 INFO - Cleaning up... 10:07:33 INFO - Return code: 0 10:07:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:07:33 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')]} 10:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:33 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')]} 10:07:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:33 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 0x10fce6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32add69d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa3cd30>, '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 0x10fa06cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa2dbe8>, '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.8Ethf33D2z/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.FrRfVDOE8T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 10:07:33 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 10:07:33 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 10:07:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Ethf33D2z/Render', 10:07:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:07:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:07:33 INFO - 'HOME': '/Users/cltbld', 10:07:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:07:33 INFO - 'LOGNAME': 'cltbld', 10:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:33 INFO - 'MOZ_NO_REMOTE': '1', 10:07:33 INFO - 'NO_EM_RESTART': '1', 10:07:33 INFO - 'PAGER': '/bin/cat', 10:07:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:33 INFO - 'PWD': '/builds/slave/test', 10:07:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:07:33 INFO - 'SHELL': '/bin/bash', 10:07:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FrRfVDOE8T/Listeners', 10:07:33 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:07:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:07:33 INFO - 'USER': 'cltbld', 10:07:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:07:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:33 INFO - 'XPC_FLAGS': '0x0', 10:07:33 INFO - 'XPC_SERVICE_NAME': '0', 10:07:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:07:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:34 INFO - Downloading/unpacking psutil>=3.1.1 10:07:34 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 10:07:34 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 10:07:34 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 10:07:34 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 10:07:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:07:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:07:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:07:35 INFO - Installing collected packages: psutil 10:07:35 INFO - Running setup.py install for psutil 10:07:35 INFO - building 'psutil._psutil_osx' extension 10:07:35 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 10:07:36 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 10:07:36 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 10:07:36 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 10:07:36 INFO - building 'psutil._psutil_posix' extension 10:07:36 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 10:07:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:07:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:07:36 INFO - ^ 10:07:36 INFO - 1 warning generated. 10:07:36 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 10:07:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:07:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:07:37 INFO - Successfully installed psutil 10:07:37 INFO - Cleaning up... 10:07:37 INFO - Return code: 0 10:07:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:07: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')]} 10:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07: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')]} 10:07:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:37 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 0x10fce6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32add69d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa3cd30>, '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 0x10fa06cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa2dbe8>, '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.8Ethf33D2z/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.FrRfVDOE8T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 10:07:37 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 10:07:37 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 10:07:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Ethf33D2z/Render', 10:07:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:07:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:07:37 INFO - 'HOME': '/Users/cltbld', 10:07:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:07:37 INFO - 'LOGNAME': 'cltbld', 10:07:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:37 INFO - 'MOZ_NO_REMOTE': '1', 10:07:37 INFO - 'NO_EM_RESTART': '1', 10:07:37 INFO - 'PAGER': '/bin/cat', 10:07:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:07:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:37 INFO - 'PWD': '/builds/slave/test', 10:07:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:07:37 INFO - 'SHELL': '/bin/bash', 10:07:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FrRfVDOE8T/Listeners', 10:07:37 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:07:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:07:37 INFO - 'USER': 'cltbld', 10:07:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:07:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:07:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:37 INFO - 'XPC_FLAGS': '0x0', 10:07:37 INFO - 'XPC_SERVICE_NAME': '0', 10:07:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:07:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:07:38 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 10:07:38 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 10:07:38 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 10:07:38 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 10:07:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:07:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:07:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:07:39 INFO - Installing collected packages: mozsystemmonitor 10:07:39 INFO - Running setup.py install for mozsystemmonitor 10:07:39 INFO - Successfully installed mozsystemmonitor 10:07:39 INFO - Cleaning up... 10:07:39 INFO - Return code: 0 10:07:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:07:39 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')]} 10:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:39 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')]} 10:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:39 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 0x10fce6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32add69d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa3cd30>, '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 0x10fa06cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa2dbe8>, '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.8Ethf33D2z/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.FrRfVDOE8T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 10:07:39 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 10:07:39 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 10:07:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Ethf33D2z/Render', 10:07:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:07:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:07:39 INFO - 'HOME': '/Users/cltbld', 10:07:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:07:39 INFO - 'LOGNAME': 'cltbld', 10:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:39 INFO - 'MOZ_NO_REMOTE': '1', 10:07:39 INFO - 'NO_EM_RESTART': '1', 10:07:39 INFO - 'PAGER': '/bin/cat', 10:07:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:39 INFO - 'PWD': '/builds/slave/test', 10:07:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:07:39 INFO - 'SHELL': '/bin/bash', 10:07:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FrRfVDOE8T/Listeners', 10:07:39 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:07:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:07:39 INFO - 'USER': 'cltbld', 10:07:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:07:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:39 INFO - 'XPC_FLAGS': '0x0', 10:07:39 INFO - 'XPC_SERVICE_NAME': '0', 10:07:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:40 INFO - Downloading/unpacking jsonschema==2.5.1 10:07:40 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 10:07:40 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 10:07:40 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 10:07:40 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 10:07:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:07:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:07:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:07:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:07:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:07:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:07:41 INFO - Downloading functools32-3.2.3-2.tar.gz 10:07:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:07:41 INFO - warning: no files found matching '*.txt' 10:07:41 INFO - no previously-included directories found matching 'build' 10:07:41 INFO - no previously-included directories found matching 'dist' 10:07:41 INFO - no previously-included directories found matching '.git*' 10:07:41 INFO - Installing collected packages: jsonschema, functools32 10:07:41 INFO - Running setup.py install for functools32 10:07:42 INFO - warning: no files found matching '*.txt' 10:07:42 INFO - no previously-included directories found matching 'build' 10:07:42 INFO - no previously-included directories found matching 'dist' 10:07:42 INFO - no previously-included directories found matching '.git*' 10:07:42 INFO - Successfully installed jsonschema functools32 10:07:42 INFO - Cleaning up... 10:07:42 INFO - Return code: 0 10:07:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:07:42 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')]} 10:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:42 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')]} 10:07:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:42 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 0x10fce6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32add69d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa3cd30>, '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 0x10fa06cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa2dbe8>, '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.8Ethf33D2z/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.FrRfVDOE8T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 10:07:42 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 10:07:42 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 10:07:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Ethf33D2z/Render', 10:07:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:07:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:07:42 INFO - 'HOME': '/Users/cltbld', 10:07:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:07:42 INFO - 'LOGNAME': 'cltbld', 10:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:42 INFO - 'MOZ_NO_REMOTE': '1', 10:07:42 INFO - 'NO_EM_RESTART': '1', 10:07:42 INFO - 'PAGER': '/bin/cat', 10:07:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:07:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:42 INFO - 'PWD': '/builds/slave/test', 10:07:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:07:42 INFO - 'SHELL': '/bin/bash', 10:07:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FrRfVDOE8T/Listeners', 10:07:42 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:07:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:07:42 INFO - 'USER': 'cltbld', 10:07:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:07:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:42 INFO - 'XPC_FLAGS': '0x0', 10:07:42 INFO - 'XPC_SERVICE_NAME': '0', 10:07:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:07:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:07:42 INFO - Cleaning up... 10:07:42 INFO - Return code: 0 10:07:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:07:42 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')]} 10:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:42 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')]} 10:07:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:42 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 0x10fce6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32add69d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa3cd30>, '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 0x10fa06cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa2dbe8>, '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.8Ethf33D2z/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.FrRfVDOE8T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 10:07:42 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 10:07:42 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 10:07:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Ethf33D2z/Render', 10:07:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:07:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:07:42 INFO - 'HOME': '/Users/cltbld', 10:07:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:07:42 INFO - 'LOGNAME': 'cltbld', 10:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:42 INFO - 'MOZ_NO_REMOTE': '1', 10:07:42 INFO - 'NO_EM_RESTART': '1', 10:07:42 INFO - 'PAGER': '/bin/cat', 10:07:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:07:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:42 INFO - 'PWD': '/builds/slave/test', 10:07:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:07:42 INFO - 'SHELL': '/bin/bash', 10:07:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FrRfVDOE8T/Listeners', 10:07:42 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:07:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:07:42 INFO - 'USER': 'cltbld', 10:07:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:07:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:42 INFO - 'XPC_FLAGS': '0x0', 10:07:42 INFO - 'XPC_SERVICE_NAME': '0', 10:07:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:07:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:42 INFO - Downloading/unpacking blobuploader==1.2.4 10:07:42 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 10:07:42 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 10:07:42 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 10:07:42 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 10:07:44 INFO - Downloading blobuploader-1.2.4.tar.gz 10:07:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:07:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:07: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 10:07: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 10:07: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 10:07: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 10:07:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:07:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:07: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 10:07: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 10:07: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 10:07: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 10:07:44 INFO - Downloading docopt-0.6.1.tar.gz 10:07:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:07:45 INFO - Installing collected packages: blobuploader, requests, docopt 10:07:45 INFO - Running setup.py install for blobuploader 10:07:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:07:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:07:45 INFO - Running setup.py install for requests 10:07:45 INFO - Running setup.py install for docopt 10:07:45 INFO - Successfully installed blobuploader requests docopt 10:07:45 INFO - Cleaning up... 10:07:45 INFO - Return code: 0 10:07:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:07:45 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')]} 10:07:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:45 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')]} 10:07:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:45 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 0x10fce6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32add69d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa3cd30>, '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 0x10fa06cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa2dbe8>, '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.8Ethf33D2z/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.FrRfVDOE8T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 10:07:45 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 10:07:45 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 10:07:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Ethf33D2z/Render', 10:07:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:07:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:07:45 INFO - 'HOME': '/Users/cltbld', 10:07:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:07:45 INFO - 'LOGNAME': 'cltbld', 10:07:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:45 INFO - 'MOZ_NO_REMOTE': '1', 10:07:45 INFO - 'NO_EM_RESTART': '1', 10:07:45 INFO - 'PAGER': '/bin/cat', 10:07:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:07:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:45 INFO - 'PWD': '/builds/slave/test', 10:07:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:07:45 INFO - 'SHELL': '/bin/bash', 10:07:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FrRfVDOE8T/Listeners', 10:07:45 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:07:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:07:45 INFO - 'USER': 'cltbld', 10:07:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:07:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:07:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:45 INFO - 'XPC_FLAGS': '0x0', 10:07:45 INFO - 'XPC_SERVICE_NAME': '0', 10:07:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:07:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:07:46 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-3btMRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:07:46 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-IB96CL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:07:46 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-u5fi0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:07:46 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-KV1gTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:07:46 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-dmNqo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:07:46 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-_qDMKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:07:46 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-FBFY4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:07:46 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-l06Jou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:07:46 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-CgJomh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:07:47 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-4GxZVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:07:47 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-ms4Lnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:07:47 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-M4YVzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:07:47 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-ZWEAij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:07:47 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-fcpRQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:07:47 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Tf6rZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:07:47 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-CA7Qmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:07:47 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-bt0Vle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:07:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:07:47 INFO - Running setup.py install for manifestparser 10:07:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:07:48 INFO - Running setup.py install for mozcrash 10:07:48 INFO - Running setup.py install for mozdebug 10:07:48 INFO - Running setup.py install for mozdevice 10:07:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:07:48 INFO - Running setup.py install for mozfile 10:07:48 INFO - Running setup.py install for mozhttpd 10:07:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:07:48 INFO - Running setup.py install for mozinfo 10:07:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:07:48 INFO - Running setup.py install for mozInstall 10:07:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:07:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:07:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:07:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:07:49 INFO - Running setup.py install for mozleak 10:07:49 INFO - Running setup.py install for mozlog 10:07:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:07:49 INFO - Running setup.py install for moznetwork 10:07:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:07:49 INFO - Running setup.py install for mozprocess 10:07:49 INFO - Running setup.py install for mozprofile 10:07:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:07:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:07:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:07:49 INFO - Running setup.py install for mozrunner 10:07:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:07:50 INFO - Running setup.py install for mozscreenshot 10:07:50 INFO - Running setup.py install for moztest 10:07:50 INFO - Running setup.py install for mozversion 10:07:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:07:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:07:50 INFO - Cleaning up... 10:07:50 INFO - Return code: 0 10:07:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:07:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:07:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:50 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 0x10fce6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32add69d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa3cd30>, '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 0x10fa06cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa2dbe8>, '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.8Ethf33D2z/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.FrRfVDOE8T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 10:07:50 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 10:07:50 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 10:07:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Ethf33D2z/Render', 10:07:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:07:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:07:50 INFO - 'HOME': '/Users/cltbld', 10:07:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:07:50 INFO - 'LOGNAME': 'cltbld', 10:07:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:50 INFO - 'MOZ_NO_REMOTE': '1', 10:07:50 INFO - 'NO_EM_RESTART': '1', 10:07:50 INFO - 'PAGER': '/bin/cat', 10:07:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:07:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:50 INFO - 'PWD': '/builds/slave/test', 10:07:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:07:50 INFO - 'SHELL': '/bin/bash', 10:07:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FrRfVDOE8T/Listeners', 10:07:50 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:07:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:07:50 INFO - 'USER': 'cltbld', 10:07:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:07:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:07:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:50 INFO - 'XPC_FLAGS': '0x0', 10:07:50 INFO - 'XPC_SERVICE_NAME': '0', 10:07:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:07:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:07:50 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-mt3F4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:07:50 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)) 10:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:07:50 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-ndmo49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:07:51 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)) 10:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:07:51 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-qsyZU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:07:51 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)) 10:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:07:51 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-yTe94y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:07:51 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)) 10:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:07:51 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-uc4cuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:07:51 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)) 10:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:07:51 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-INnMT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:07:51 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)) 10:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:07:51 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-ayYDne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:07:51 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)) 10:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:07:51 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-mS6cxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:07:51 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)) 10:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:07:51 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-lEvh6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:07:51 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)) 10:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:07:51 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-XW_aTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:07:51 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)) 10:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:07:51 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-KxDCm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:07:51 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)) 10:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:07:51 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Pryus8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:07:52 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)) 10:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:07:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-h9_oWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:07:52 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)) 10:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:07:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-10HEXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:07:52 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)) 10:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:07:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-v7o8H_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:07:52 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)) 10:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:07:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-K_4kLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:07:52 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)) 10:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:07:52 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-3lO4El-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:07:52 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)) 10:07:52 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)) 10:07:52 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)) 10:07:52 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)) 10:07:52 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)) 10:07:52 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)) 10:07:52 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)) 10:07:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:07: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 10:07: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 10:07: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 10:07: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 10:07:54 INFO - Downloading blessings-1.6.tar.gz 10:07:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:07:54 INFO - Installing collected packages: blessings 10:07:54 INFO - Running setup.py install for blessings 10:07:54 INFO - Successfully installed blessings 10:07:54 INFO - Cleaning up... 10:07:54 INFO - Return code: 0 10:07:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:07:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:07:54 INFO - Reading from file tmpfile_stdout 10:07:54 INFO - Current package versions: 10:07:54 INFO - blessings == 1.6 10:07:54 INFO - blobuploader == 1.2.4 10:07:54 INFO - docopt == 0.6.1 10:07:54 INFO - functools32 == 3.2.3-2 10:07:54 INFO - jsonschema == 2.5.1 10:07:54 INFO - manifestparser == 1.1 10:07:54 INFO - mozInstall == 1.13 10:07:54 INFO - mozcrash == 1.0 10:07:54 INFO - mozdebug == 0.1 10:07:54 INFO - mozdevice == 0.51 10:07:54 INFO - mozfile == 1.2 10:07:54 INFO - mozhttpd == 0.7 10:07:54 INFO - mozinfo == 0.10 10:07:54 INFO - mozleak == 0.1 10:07:54 INFO - mozlog == 3.4 10:07:54 INFO - moznetwork == 0.27 10:07:54 INFO - mozprocess == 0.25 10:07:54 INFO - mozprofile == 0.28 10:07:54 INFO - mozrunner == 6.13 10:07:54 INFO - mozscreenshot == 0.1 10:07:54 INFO - mozsystemmonitor == 0.3 10:07:54 INFO - moztest == 0.8 10:07:54 INFO - mozversion == 1.4 10:07:54 INFO - psutil == 3.1.1 10:07:54 INFO - requests == 1.2.3 10:07:54 INFO - wsgiref == 0.1.2 10:07:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:07:54 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')]} 10:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:54 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')]} 10:07:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:54 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 0x10fce6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32add69d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa3cd30>, '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 0x10fa06cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa2dbe8>, '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.8Ethf33D2z/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.FrRfVDOE8T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 10:07:54 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 10:07:54 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 10:07:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Ethf33D2z/Render', 10:07:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:07:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:07:54 INFO - 'HOME': '/Users/cltbld', 10:07:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:07:54 INFO - 'LOGNAME': 'cltbld', 10:07:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:54 INFO - 'MOZ_NO_REMOTE': '1', 10:07:54 INFO - 'NO_EM_RESTART': '1', 10:07:54 INFO - 'PAGER': '/bin/cat', 10:07:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:07:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:54 INFO - 'PWD': '/builds/slave/test', 10:07:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:07:54 INFO - 'SHELL': '/bin/bash', 10:07:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FrRfVDOE8T/Listeners', 10:07:54 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:07:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:07:54 INFO - 'USER': 'cltbld', 10:07:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:07:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:07:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:54 INFO - 'XPC_FLAGS': '0x0', 10:07:54 INFO - 'XPC_SERVICE_NAME': '0', 10:07:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:07:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:54 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)) 10:07:54 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)) 10:07:54 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)) 10:07:54 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)) 10:07:54 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)) 10:07:54 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)) 10:07:54 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)) 10:07:54 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)) 10:07:54 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)) 10:07:54 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:07: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 10:07: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 10:07: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 10:07: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 10:07:56 INFO - Downloading marionette_driver-1.4.0.tar.gz 10:07:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 10:07:56 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)) 10:07:56 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)) 10:07:56 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)) 10:07:56 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)) 10:07:56 INFO - Installing collected packages: marionette-driver 10:07:56 INFO - Running setup.py install for marionette-driver 10:07:56 INFO - Successfully installed marionette-driver 10:07:56 INFO - Cleaning up... 10:07:56 INFO - Return code: 0 10:07:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:07:56 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')]} 10:07:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:56 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')]} 10:07:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:56 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 0x10fce6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa32add69d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa3cd30>, '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 0x10fa06cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa2dbe8>, '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.8Ethf33D2z/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.FrRfVDOE8T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 10:07:56 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 10:07:56 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 10:07:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Ethf33D2z/Render', 10:07:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:07:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:07:56 INFO - 'HOME': '/Users/cltbld', 10:07:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:07:56 INFO - 'LOGNAME': 'cltbld', 10:07:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:56 INFO - 'MOZ_NO_REMOTE': '1', 10:07:56 INFO - 'NO_EM_RESTART': '1', 10:07:56 INFO - 'PAGER': '/bin/cat', 10:07:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:07:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:07:56 INFO - 'PWD': '/builds/slave/test', 10:07:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:07:56 INFO - 'SHELL': '/bin/bash', 10:07:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FrRfVDOE8T/Listeners', 10:07:56 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:07:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:07:56 INFO - 'USER': 'cltbld', 10:07:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:07:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:07:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:56 INFO - 'XPC_FLAGS': '0x0', 10:07:56 INFO - 'XPC_SERVICE_NAME': '0', 10:07:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:07:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:07:56 INFO - Cleaning up... 10:07:56 INFO - Return code: 0 10:07:56 INFO - Running post-action listener: _resource_record_post_action 10:07:56 INFO - Running post-action listener: _start_resource_monitoring 10:07:56 INFO - Starting resource monitoring. 10:07:56 INFO - [mozharness: 2017-06-13 17:07:56.986261Z] Finished create-virtualenv step (success) 10:07:56 INFO - [mozharness: 2017-06-13 17:07:56.987229Z] Running install step. 10:07:56 INFO - Running pre-action listener: _resource_record_pre_action 10:07:56 INFO - Running main action method: install 10:07:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:07:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:07:57 INFO - Reading from file tmpfile_stdout 10:07:57 INFO - Detecting whether we're running mozinstall >=1.0... 10:07:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:07:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:07:57 INFO - Reading from file tmpfile_stdout 10:07:57 INFO - Output received: 10:07:57 INFO - Usage: mozinstall [options] installer 10:07:57 INFO - Options: 10:07:57 INFO - -h, --help show this help message and exit 10:07:57 INFO - -d DEST, --destination=DEST 10:07:57 INFO - Directory to install application into. [default: 10:07:57 INFO - "/builds/slave/test"] 10:07:57 INFO - --app=APP Application being installed. [default: firefox] 10:07:57 INFO - mkdir: /builds/slave/test/build/application 10:07:57 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'] 10:07:57 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 10:08:19 INFO - Reading from file tmpfile_stdout 10:08:19 INFO - Output received: 10:08:19 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:08:19 INFO - Running post-action listener: _resource_record_post_action 10:08:19 INFO - [mozharness: 2017-06-13 17:08:19.055247Z] Finished install step (success) 10:08:19 INFO - [mozharness: 2017-06-13 17:08:19.055380Z] Running setup-mitmproxy step. 10:08:19 INFO - Running pre-action listener: _resource_record_pre_action 10:08:19 INFO - Running main action method: setup_mitmproxy 10:08:19 INFO - Skipping: mitmproxy is not required 10:08:19 INFO - Running post-action listener: _resource_record_post_action 10:08:19 INFO - [mozharness: 2017-06-13 17:08:19.055971Z] Finished setup-mitmproxy step (success) 10:08:19 INFO - [mozharness: 2017-06-13 17:08:19.056077Z] Running run-tests step. 10:08:19 INFO - Running pre-action listener: _resource_record_pre_action 10:08:19 INFO - Running main action method: run_tests 10:08:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:08:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:08:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:08:19 INFO - 2017-06-13 10:08:19.070 screenresolution[1988:8804] starting screenresolution argv=screenresolution get 10:08:19 INFO - 2017-06-13 10:08:19.073 screenresolution[1988:8804] Display 0: 1600x1200x32@60 10:08:19 INFO - 2017-06-13 10:08:19.079 screenresolution[1989:8806] starting screenresolution argv=screenresolution list 10:08:19 INFO - Available Modes on Display 0 10:08:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:08:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:08:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:08:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:08:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:08:19 INFO - Intel Iris: 10:08:19 INFO - Chipset Model: Intel Iris 10:08:19 INFO - Type: GPU 10:08:19 INFO - Bus: Built-In 10:08:19 INFO - VRAM (Dynamic, Max): 1536 MB 10:08:19 INFO - Vendor: Intel (0x8086) 10:08:19 INFO - Device ID: 0x0a2e 10:08:19 INFO - Revision ID: 0x0009 10:08:19 INFO - Displays: 10:08:19 INFO - Display: 10:08:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:08:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:08:19 INFO - Display Serial Number: 1600x1200 60 10:08:19 INFO - Main Display: Yes 10:08:19 INFO - Mirror: Off 10:08:19 INFO - Online: Yes 10:08:19 INFO - Rotation: Supported 10:08:19 INFO - Return code: 0 10:08:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:08:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:08:19 INFO - Python 2.7.3 10:08:19 INFO - Return code: 0 10:08:19 INFO - grabbing minidump binary from tooltool 10:08:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:08:19 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 0x10fa3cd30>, '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 0x10fa06cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa2dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:08:19 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 10:08:19 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 10:08:19 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 10:08:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:08:19 INFO - Return code: 0 10:08:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:08:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:08:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:08:19 INFO - ENV: RUST_BACKTRACE is now 1 10:08:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:08:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:08:19 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/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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 10:08:19 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/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0167 --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 10:08:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8Ethf33D2z/Render', 10:08:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:08:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:08:19 INFO - 'HOME': '/Users/cltbld', 10:08:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:08:19 INFO - 'LOGNAME': 'cltbld', 10:08:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:08:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:08:19 INFO - 'MOZ_NO_REMOTE': '1', 10:08:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:08:19 INFO - 'NO_EM_RESTART': '1', 10:08:19 INFO - 'PAGER': '/bin/cat', 10:08:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:08:19 INFO - 'PWD': '/builds/slave/test', 10:08:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:08:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:08:19 INFO - 'RUST_BACKTRACE': '1', 10:08:19 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:08:19 INFO - 'SHELL': '/bin/bash', 10:08:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FrRfVDOE8T/Listeners', 10:08:19 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 10:08:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:08:19 INFO - 'USER': 'cltbld', 10:08:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:08:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:08:19 INFO - 'XPC_FLAGS': '0x0', 10:08:19 INFO - 'XPC_SERVICE_NAME': '0', 10:08:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:08:19 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/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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 10:08:19 INFO - mozversion application_buildid: 20170613090034 10:08:19 INFO - mozversion application_changeset: 02caa113457b2780c13bf0d7c26cb739558b5f5b 10:08:19 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:08:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:08:19 INFO - mozversion application_name: Firefox 10:08:19 INFO - mozversion application_remotingname: firefox-dev 10:08:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:08:19 INFO - mozversion application_vendor: Mozilla 10:08:19 INFO - mozversion application_version: 55.0 10:08:19 INFO - mozversion platform_buildid: 20170613090034 10:08:19 INFO - mozversion platform_changeset: 02caa113457b2780c13bf0d7c26cb739558b5f5b 10:08:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:08:19 INFO - mozversion platform_version: 55.0 10:08:19 INFO - using testdate: 1497373699 10:08:19 INFO - actual date: 1497373699 10:08:19 INFO - starting webserver on 'localhost:49277' 10:08:19 INFO - SUITE-START | Running 2 tests 10:08:19 INFO - TEST-START | dromaeo_css 10:08:19 INFO - operating with platform_type : mac_ 10:08:19 INFO - Initialising browser for dromaeo_css test... 10:08:19 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49277/getInfo.html -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmp273oyA/profile 10:08:19 INFO - TEST-INFO | started process 1999 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49277/getInfo.html -foreground) 10:08:53 INFO - TEST-INFO | 1999: killed by SIGTERM 10:08:53 INFO - Browser initialized. 10:08:53 INFO - Running cycle 1/1 for dromaeo_css test... 10:08:53 INFO - TEST-INFO | started process 2008 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmp273oyA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:08:55 INFO - PID 2008 | 2017-06-13 10:08:55.132 plugin-container[2009:9465] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 10:08:55 INFO - PID 2008 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:08:55 INFO - PID 2008 | 2017-06-13 10:08:55.132 plugin-container[2009:9465] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4823, name = 'com.apple.CFPasteboardClient' 10:08:55 INFO - PID 2008 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:08:55 INFO - PID 2008 | 2017-06-13 10:08:55.132 plugin-container[2009:9465] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:08:55 INFO - PID 2008 | 2017-06-13 10:08:55.876 plugin-container[2010:9508] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 10:08:55 INFO - PID 2008 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:08:55 INFO - PID 2008 | 2017-06-13 10:08:55.876 plugin-container[2010:9508] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x484b, name = 'com.apple.CFPasteboardClient' 10:08:55 INFO - PID 2008 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:08:55 INFO - PID 2008 | 2017-06-13 10:08:55.876 plugin-container[2010:9508] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:09:05 INFO - PID 2008 | RSS: Main: 214839296 10:09:05 INFO - PID 2008 | 10:12:07 INFO - PID 2008 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 10:12:10 INFO - PID 2008 | RSS: Main: 232189952 10:12:10 INFO - PID 2008 | 10:15:11 INFO - PID 2008 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 10:15:12 INFO - PID 2008 | RSS: Main: 240013312 10:15:12 INFO - PID 2008 | 10:18:13 INFO - PID 2008 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 10:18:14 INFO - PID 2008 | RSS: Main: 244215808 10:18:14 INFO - PID 2008 | 10:21:16 INFO - PID 2008 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 10:21:16 INFO - PID 2008 | RSS: Main: 245985280 10:21:16 INFO - PID 2008 | 10:24:19 INFO - PID 2008 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 10:24:20 INFO - PID 2008 | RSS: Main: 246550528 10:24:20 INFO - PID 2008 | 10:27:21 INFO - PID 2008 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 10:27:22 INFO - PID 2008 | RSS: Main: 244051968 10:27:22 INFO - PID 2008 | 10:27:22 INFO - PID 2008 | __start_tp_report 10:27:22 INFO - PID 2008 | _x_x_mozilla_page_load 10:27:22 INFO - PID 2008 | _x_x_mozilla_page_load_details 10:27:22 INFO - PID 2008 | |i|pagename|runs| 10:27:22 INFO - PID 2008 | |0;dojo.html;2983.7314641682246;3086.475299199136;3672.5042119313903;3715.962840371597;3722.0694826293425;5246.317978662773;5410.62125651204;6316.115743795872;6372.681365931708;6441.6779161041995;6358.968205158968;6393.0730044143575;6415.358464153583;6423.293437721847;6433.839154021151;5550.111982082867;5600.355959064719;5616.410276920926;5661.377248502682;5726.226959360475;5452.291202143716;5587.888242235153;5643.746031428595;5656.349519805213;5689.317281926154;5489.066858634042;5508.531774799147;5559.499645031946;5568.025595520768;5582.190582365575;5602.299712535933;5616.382197958222;5627.8593035174035;5632.295963004624;5664.716764161817;5476.698781567012;5567.944320556783;5597.104463285855;5603.943960560383;5638.661680299195;6287.1198032275515;6367.6179429233935;6375.203099612549;6376.29860715321;6395.808125756234;6666.533342666015;6752.898706519357;6879.380855723009;6913.1358580177475;6951.16586009682;6853.314668533157;6883.27725588811;6931.445484361239;6986.40615547679;7013.544119632259;6410.8717825643225;6470.352964703586;6536.477081833537;6536.8365790855605;6544.44372228366;6028.186194863725;7179.676914538858;7180.748673796392;7185.784426467214;7199.856002879913;6565.901511477332;6674.799756007327;6677;6680.966595166993;6694.531382803157;6820.147481564804;6853.143357080365;6858.759943401957;6951.44388448933;7013.89479157813;6843.452523798084;6851.109355783717;6868.381845633917;6896.586204827727;6913.688884000232;6875.346842049998;6885.758998435131;6891.414229790524;6915.619640919797;6926.099607051051;5378.327709036371;5410.702411367412;5419.729013549307;5423.105187644073;5453.727313634303;4251.957480425218;4834.564889159992;4893.559579637778;4960.801567937242;4990.850274491771;5978.701064946736;6304.936950630435;6513.088167656532;6529.902051469232;6539.673016349164;7015.89476157858;7038.92961070393;7044.577325360392;7046.577205367695;7072.646367681596;5441.265429167091;6492.67536623167;6511.413972742381;6513.12072870166;6550.639714815636;5966.820995370146;6059.394060593905;6068.423499767515;6104.847378815476;6131.540134489931;6966.094407726861;7022.824429389204;7035.155781306072;7038.2609825969585;7048.894266586005;6113.786017489367;6185.876282474235;6912.585244885323;6996.545224560387;7026.367626913602;7200.603966781878;7209.71161153548;7215.206327304098;7229.240929702306;7242.710291588278;5699.658020518782;6922.723091076301;6931.133608298963;6935.75724849648;6965.5124141309625;7009.719611215494;7067.82330441743;7068.187158476841;7074;7075.469339799432;5593.214355063586;6541.215054193527;6639.734410623521;6713.160854893138;6808.795736127924;6421.935780642134;6784.660766961533;6798.932010680028;6818.93181068183;6837.418819400406;6408.423241908251;6416.422521972858;6529.053287273414;6533.771318003847;6691.5650482718465;6612.933870661232;6647.833804154935;6662.034005069335;6718.697658605277;6786.117804685359;6463.741450341934;6630.972199309115;6638.435732962559;6644.269130395749;6651.900221496779 10:27:22 INFO - PID 2008 | |1;ext.html;810.3800592546703;831.9667213311468;841.8821365008898;854.8162145138793;863.9265662418694;37768;38609.03477413066;38610.8069459653;38674.8066259669;38743;38927.80536097322;38948.83153505399;39060;39074.21851562967;39228.60771392285;7556.5466072035715;7685.846283074336;7693.499922505047;7697.730579429721;7719.768406947801;6921.377076063153;7572.318491335779;7666.769996900102;7667.923320766805;8306.958465207665;8481.067082620906;8512.404131710782;8513.404061715652;8519.190676885686;8533.359998000144;8445.957770211171;8475.49147051179;8490.66037358505;8497.597896347095;8516.574171291442;8291.585420729;8310.293625041879;8349.8330033399;8355.373346998938;8396.328293736486;37047.25905481902;37157.442638360444;37189.442158367645;37395.439068413994;37397.626023739685;3611.1513794258326;3630.6369363063745;3648;3652.3243200008083;3653.4702468141973;30717.846410768027;30746.385072298654;30749.69250307513;30751.692483075105;30913.918012869442;2752.545829938058;3096.411681780477;3151.952720709191;3181.5068664357063;3197.232664160608;255663.99999999814;259211;259219;337495;337948;49720.756981075036;50177.74911125421;50503.98992020139;50581.494185058415;50649.73375665638;51974;52189.69525761886;52376.21435678468;52386.73806631019;52472.737636311576;13049.804252936214;15415.768763468557;15537.84462155364;15605.68788624221;15606.297716602554;12709.745805083847;12771.169873958162;12779.424925878358;12779.872201278054;12782.872171278168;13934.094283871718;14102.435902563988;14129.717405651829;14138.363773630212;14178.929105354408;6165.537584681167;6183.84540386494;6216.7824126155365;6235.3452887446165;6247;910.7905181808221;920.8250432417819;956.2780101023683;994.8060128275062;1048.554364395129;22742;23403.53192936166;23424.531509369717;23729.525409491715;24522.87738561296;966.6133546581424;1063.1441689440076;1097.4567589043475;1126.785732013628;1178.776032553812;11026;12717.809232861515;12728.745425091447;12737.617871463872;12815.29515876617;365770;367414;368101.15949420084;368541;369765;55318.72340638351;55804;56015.159772603445;56203.71898140483;56308.718456407456;1376.3049659921676;1463.8902082343866;1547.0794877048363;1554.8911576189785;1571.2143928035982;20054.49863753418;22834.31497055091;22993.88503057474;23333.883330583238;23550.29349119529;39626.60373396229;39658.60341396549;39672.00819979524;39710.80144599374;39724.006899827735;288885.55557222076;288994;289263;289410.5529472339;290700;29952.70047299499;30421.087367379016;30493.23766905845;30534.236644084074;36211.63788362188;43415;43758.343624845016;44317.33523997207;44863.775681122694;45352.77323613361;27684;27712.44575108527;28372.716272837006;30064;30603.846980764953;29618.55572166376;30465.847670761505;31169.064928052194;31225.37549249048;31230.687693123687;30312.69687303099;36806.05551555923;37027.70403035881;37077;37114.81442592878;29660.555091674072;29752;29892;30890.53664194994;31656.841715791274;22604;24308.270751877473;24311.83263129999;24520;24545.754542454346 10:27:22 INFO - PID 2008 | |2;jquery.html;471.84429138384337;498.96215870988345;505.7319620601083;511.0775051032887;534.1267028408556;21005.68491472629;21953.341399757992;22338.553228935412;22511.54976900461;22701.318960431214;24641.26076217712;24678.75321246787;24886.751132488716;25042.749572504315;25045;6222.7199776010075;6379.5853269537565;6391.808245752635;6402.519811014169;6405.807825765234;6275.843103922392;6363.236411630611;6387.840303992414;6392.041193820917;6404.039394090877;5133.691978481284;5653.434656534355;6282.371762823726;6312.989921612543;6329.398707122816;6225.968870155642;6272.717727702265;6291.591046581958;6299.811005669837;6317.6209427434505;5758.481736643722;5762.4525670061275;5779.190913272145;5806.680632565207;5810.970945145247;26807.86596067007;28712.856435717687;29343.826246950095;29865.850670746724;30616.54075188874;2324.9186278480342;2327.918522851709;2350.8589484630807;2366.6568347589505;2373.6320870265135;19260.903695481433;19482.220711171536;19786.109625066907;19822.008899554963;19870;1951.6291904538093;2177.7822217778094;2230.955380892373;2241.8766967816764;2254.0645632062674;167088.16455917765;174015;182514;183766;187745.99999999863;14295.499657511937;14567.781483277759;15019.24903754808;15095.245237738069;15666.138362390178;20445.897770511052;20476.488087797923;20486.487837803877;20503.17987280522;20503.58992820165;9277.211437027929;10029.69910902674;10127.59489620407;10263.58945642166;10277.537510812032;9094.090590940854;9465.763355916019;9675.419474831391;9717.22262219035;9724.805503889882;7452.552846829208;7605.581693006938;8364.958175209207;9033.77415564616;9127.68053118151;2354.682117914094;2561.052410608087;2593.196109206152;2634.1175706138274;2642.748938850806;936.194872409727;937.8780758501351;940.849464085743;1009.3136667066318;1011.5953618552638;19717.60564788696;20048.89975550113;21109.366718998455;21247.468813279793;22007.11971521153;993.9701808945744;1001.6494227020485;1011.595361855249;1046.1892033673812;1046.6650671785108;9111.316651251183;10488.68533943983;10756.677299681021;10776.568937242422;11471.713207169887;556995;573852;583649;587227;588003;132094.33952830173;158805.2059739694;160438;163269.18365408096;164267.17866410592;935.2985261054209;993.1111655068673;1047.177965297238;1130.5477808876515;1202.1825158891863;22191.334259972227;24178.274651760476;24179.51640967206;24439.02243910224;24482.877585611957;50710.98578028493;66717;67792.6610366945;68619;68812;60643.39356606377;78731;88449;89590;93005;11110.722231944266;11467;11469.082473402275;11595.478203480694;11616.709582260512;18048.368307109187;18083.457496275132;18148.455546333633;18457.077146142907;18491.44525664232;14482.855171448573;14923.253837308308;15168.696626067638;15234.542963711106;15250;10812.67561973142;13439.328033598085;13519.459221631025;13718.38267277955;13858.307084645929;15415.306311216247;15540.922295388451;15657.138857362954;15666.764998524803;15684.686306273583;11268.323900565993;11452.08383329315;11469.885301147215;11473.655790326304;11476.770464590829;13904.58286251414;14645.340959657055;14651.267436628339;14752.188629625474;14953.25233738287 10:27:22 INFO - PID 2008 | |3;mootools.html;1472.3080152328405;1525.7863899054141;1535.0559405965328;1536.77716731074;1552.3169805285677;13481.191128532282;14037.368318425682;14061.578152655436;14086.64783380416;14086.943479239073;11478.253913495617;11793.823092653596;11798.58704945327;11814.113941454403;13616.863831361683;2771.459565384746;2836.276749428893;2837.489251934651;2838.3188034871687;2846.7437930586243;2759.2550011496983;2792.176307989138;2794.804363694542;2797.6642802863685;2799.286182023581;2751.2159034675124;2779.499690055789;2786;2786.1362977477047;2790.9162725118176;2663.1344812935777;2789.442111577682;2849.8575071246564;2857.9997001049464;2863.727945845141;2527.0018342754693;2830.391465834835;2833.914982550527;2854.771618270554;2896.3772788850288;13443.731125377437;13814.240216788072;13891.166530008131;13892.93053534736;13908.235047072407;7510.436717246228;7868.252516011027;7884.960575197088;7891.842163156763;7892.842143157105;4687.88280292992;4889.29105279736;4950.0842344166085;4960.6527543072;4968.527989840959;1387.9653008674763;1419.8793102586292;1434.26852305324;1435.899487035908;1436.1526699247495;20359.287424940205;20747.481312967295;20819.479513012146;20894.791052089437;20962.371128866005;5465.508104270555;6542.443892269115;6579.638119903356;6608.669566521751;6759.526833121634;14208.076475029296;14292.49976250847;14331.570052898429;14474.927625362016;14502.492412765503;7390.076240469942;7601.7719468416035;7633.084029916335;7639.656215470317;7659.9234007660325;6945.506716056072;6946.16646002483;7036.577805331696;7066.222715501291;7111.53775004623;5108.66793658411;5242.606804489602;5254.211868219798;5273.288106105703;5283.128283833202;1518.916459594711;1520.7490764023812;1529.07490967965;1529.9541013769606;1542.2674229740783;818.7841056031788;819.3445243804932;848;849.3757088539918;866.432486721186;7198.640067996579;7361.190269070398;7454.40364770817;7706.229377062361;8174.550399728072;681.4514315975687;699.7585832887646;858.3948316436897;967.7870868408938;1013.8428543575758;4320.503142138631;4760.500147484534;4839.9758001210475;5276.419593844674;5311.548518375904;17033.63730901525;17322.22050007753;18161.182746776427;18890.43328700141;18901.14944827486;17188.52703913943;18771.530711732044;18933.431997040112;18936.24255029811;19029.238830446902;9148.451092934445;9687.612495500101;9726.319157658896;9819.06718861707;9819.509024548744;7845.803854903673;8185.690289553643;8195.42632015777;8350.916490835014;8828.558572071499;16661.750073749146;17228.483145505656;17422.303107875545;17545.912270438566;17582.296708131533;16557.089360085312;16807.747883781998;17225.39711110105;17981.460556183334;18005.549861253574;10889.564417423215;11101.611443599435;11105.777884442105;11107.611233606785;11119.16606254518;12597.370131493572;12606;12611.684707882376;12683.302418367079;12688.23870567769;9864.85202721974;10039.949800250952;10044.54799534008;10288.845667315147;10580.576776928836;9767.56045977947;9824.901750982684;10198;10205.489725513546;10233.539490723084;9330.253579713743;9356.391834531007;9425.764355890884;9444.961054283885;9452.716418507456;6583.473322134122;6813.386795188457;6871.244163142151;7045;7111.684338397413;6903.240643529308;6966.373026427443;7151.678174482265;7298.56208627484;7300.196978332486 10:27:22 INFO - PID 2008 | |4;prototype.html;416.46484267715994;422.7167797575624;433.20939285803416;444.30909935051005;466.1655636410825;5225.712585807779;6492.026196070586;6521.347865213482;6619.900701489481;6670.733170673179;6235.261201486868;6387.882120628883;6471.4175724184815;6472.546921715481;6484.870302593957;4609.723416595007;4815.855524334275;4826.686265392756;4830.154722923492;4853.878653033667;4030.334994725881;4234.618884300412;4803.375561177059;4807.35100761398;4851.320815085891;4616.699914505546;4790.832320868771;4805.639577031719;4815.566599006089;4827.782749776251;4787.904241915177;4791.209450440673;4795.160846851804;4806.327114204014;4838.782254798542;4202.747835129902;4206.348016057516;4238.597333253336;4241.851535196253;4248.214080395137;5470.617056806025;6226.564140510166;6236.750529978841;6245.781397651061;6249.656268905208;5513.669179849222;5721.370649228582;5737.254156959569;5747.856303592402;5749.425057494259;5464.3442786865835;6912.377885990235;6935.33551947534;6951.200611929592;6957.339052790027;3911.2764138634443;4842.539958703917;4852.733099679516;4858.902821943577;4868.367112275381;6198.287196972361;7314.500527391925;7332.413406927433;7358.374538164262;7380.704771809121;4278.358246263023;4948.628852836052;4963.751812409365;4971.676841005323;4978.90042199154;5026.723530205801;5036.622253331015;5049.419316778544;5050.419201791768;5057.519535644108;4718.504557021532;5164.535191832753;5725.570582206351;5740.727785162418;5756.251687280626;7045.964770176116;7053.753118640823;7060.717571297193;7089.468289878279;7096.609686467191;1464.8242210934543;1586.8730501559849;1675.798904131512;1712.1268153241936;1729.6886560419246;939.5208443693629;1015.3451024089569;1063.7766568446348;1140.7433327501246;1170.5259369955181;6362.713677884506;6369.71336289868;6473.158489396443;6520.804375868732;6527.771527996592;6835.521513494107;7223;7243.529170603894;7261.455390845707;7272.69091563513;6069.907416664954;6213.502919766498;6440.549161558646;6484.221893372825;6522.739090436425;3175.285560748813;3250.3499300140065;3339.382214290364;3609.242059167604;3769.151940813295;6248.562600617913;7384.704611815575;7399.926000740032;7428.182899881009;7437.702491900372;6880.552764070319;7413.666385012687;7417.258274172647;7422.665980030912;7466.253374662599;6819.761308354184;7365.521241119417;7396.149442814038;7403.259674032769;7423.888641670487;6348.317695810706;7169.4981351304905;7185.856282874418;7193.100862392201;7216.386607138452;6531.216254049545;6589.769358072444;6802.081718968074;7099.36105750485;7142.571445713066;6684.532082754161;6770.2891196425635;6816.795496135124;7275.090613673291;7492.175860655432;4622.907541849211;4842.7820748065715;5125.615578831677;5483.204935284281;5553.52831499661;6129.908051379144;7260.782176534713;7265.164506081868;7269.345758881726;7299.890501642586;6394;6926.653667316513;6952.130983627047;6964.676711424813;6970.756023539152;5737.254156959569;6361.8727625448155;6940.9652951734915;6952.860942781217;6961.756338528127;6049.939500604937;6734.292899245509;6818.624975626388;6847.828804279933;6869.828254293682;6925.89611155823;6940.375366217065;6947;6980.581165130108;6986.545874518343;5571.607098225443;5958.910616340671;6713.791517526892;6734.494912881455;6778.288279730557 10:27:22 INFO - PID 2008 | |5;yui.html;784.4077721320402;816.7590560784569;820.7004443378165;844.5945945945946;848.3255811629751;15146.924265378671;16903.323867045303;16985.150742462858;17060.82939170608;17163.570910727238;15439.922800385983;17962.461126166236;18616.813831861677;18640.533986650342;18660.973646449438;969.1955676788249;991.1624677147835;991.3655260633201;991.4101109839634;992.7468495533665;981.0287815063072;989.3519744567284;989.3915242126077;989.529973262698;991.1377101921303;1036.0934182590233;1037.667946257198;1051.4217180550704;1051.55834549489;1052.4737631184407;1031.0050800977056;1032.2722480651123;1040.4589613401026;1040.5421614489637;1048.3657387280662;741.9220981796941;749.34806718155;753.7060546386914;755.5806527377304;755.5957562703927;17269.913650431794;17521.912390438094;17530.21114049858;17534.947903125852;17617.647647047117;16955.576110597332;18248.817511824844;18456.53858653531;18482.445526634223;18544.350947716764;14425.06237094596;14849.70300593982;15039.849601503955;15138.016028958082;15185.696286074328;3530.135116896377;3550.680438760524;3553.022918697379;3583.4445660922597;3598.712103031751;83391.99999999939;148481.51518484784;161530.3846961527;162040;164644;13774.242416666979;13961.79057314141;14053.227072510905;14125.293735313193;14128;26254.474910502064;26365;26701.732982669924;26821.597676034875;26941.730582694316;8132.552709601029;8159.918400816035;8204;8205.425620206648;8231.711890083938;4108.465899433114;4113.876583702494;4165.166966606691;4166.0626359068965;4173.9582604173565;4079.265732168238;4695.976520117378;4725.810967561328;4756.595689366373;4803.975980120077;1025.74869157057;1044.0864243786687;1048.3762161513887;1053.7734387106655;1054.1356088007913;536.5251752199332;560.3192121572309;642.6465444005778;672.8082496488576;676.3642176354211;11410.94294528522;12143.696407589705;12368.257904525757;12503.937480312541;12590.24458532491;612.9785457508966;638.0939066525544;643.2602507116853;660.9669516524154;661.9569727967666;5166.689998600096;5483.588730845203;5648.181013753065;5823.621464604787;5909.113632955091;141691.58308416783;146412;153836.69244961336;156346.8730625381;160312;113748.29377559343;113774.99999999834;113842.43078784553;125966;133225.33387333;94180.64548386345;99105;100597.99402006033;105299.473502632;107006;11770.234934729215;12714.87285127137;12730.981521478445;12968.481260749464;13007.479700811862;45775.313370298805;46061.61815145551;46693;47927.5207247923;49078;62281;62418.06372904501;63495.36504634895;63709;66202.67594648176;17165.570860728083;17537.47387578324;18184.181711822734;18261.726074109163;18283.26866925308;18381.448556543324;18680.626387471904;18859.528511787314;19081.90459047696;19135.8086419134;17146.971181729135;17973.37093201732;17975.370862019765;18243.90878045601;18296.63406731831;17324;18021.639567208844;18217.271309147487;18238;18627.441176764718;16925.238364273882;17439.389621363698;17470.47588572345;17499.56251093733;17944.91027544854;11454.770904582028;11577.305361677989;12162.817557736464;12424.06819488524;12507.249565026128;12362.938185309376;12990.35048247565;13698.452061917811;14423.42306307736;14429.278536072945 10:27:22 INFO - PID 2008 | __end_tp_report 10:27:22 INFO - PID 2008 | __start_cc_report 10:27:22 INFO - PID 2008 | _x_x_mozilla_cycle_collect,398 10:27:22 INFO - PID 2008 | __end_cc_report 10:27:22 INFO - PID 2008 | __startTimestamp1497374842166__endTimestamp 10:27:22 INFO - PID 2008 | 10:27:22 INFO - PID 2008 | ------- Summary: start ------- 10:27:22 INFO - PID 2008 | Number of tests: 6 10:27:22 INFO - PID 2008 | 10:27:22 INFO - PID 2008 | [#0] dojo.html Cycles:165 Average:6312.77 Median:6536.84 stddev:802.76 (12.3%) stddev-sans-first:761.56 10:27:22 INFO - PID 2008 | Values: 2983.7 3086.5 3672.5 3716.0 3722.1 5246.3 5410.6 6316.1 6372.7 6441.7 6359.0 6393.1 6415.4 6423.3 6433.8 5550.1 5600.4 5616.4 5661.4 5726.2 5452.3 5587.9 5643.7 5656.3 5689.3 5489.1 5508.5 5559.5 5568.0 5582.2 5602.3 5616.4 5627.9 5632.3 5664.7 5476.7 5567.9 5597.1 5603.9 5638.7 6287.1 6367.6 6375.2 6376.3 6395.8 6666.5 6752.9 6879.4 6913.1 6951.2 6853.3 6883.3 6931.4 6986.4 7013.5 6410.9 6470.4 6536.5 6536.8 6544.4 6028.2 7179.7 7180.7 7185.8 7199.9 6565.9 6674.8 6677.0 6681.0 6694.5 6820.1 6853.1 6858.8 6951.4 7013.9 6843.5 6851.1 6868.4 6896.6 6913.7 6875.3 6885.8 6891.4 6915.6 6926.1 5378.3 5410.7 5419.7 5423.1 5453.7 4252.0 4834.6 4893.6 4960.8 4990.9 5978.7 6304.9 6513.1 6529.9 6539.7 7015.9 7038.9 7044.6 7046.6 7072.6 5441.3 6492.7 6511.4 6513.1 6550.6 5966.8 6059.4 6068.4 6104.8 6131.5 6966.1 7022.8 7035.2 7038.3 7048.9 6113.8 6185.9 6912.6 6996.5 7026.4 7200.6 7209.7 7215.2 7229.2 7242.7 5699.7 6922.7 6931.1 6935.8 6965.5 7009.7 7067.8 7068.2 7074.0 7075.5 5593.2 6541.2 6639.7 6713.2 6808.8 6421.9 6784.7 6798.9 6818.9 6837.4 6408.4 6416.4 6529.1 6533.8 6691.6 6612.9 6647.8 6662.0 6718.7 6786.1 6463.7 6631.0 6638.4 6644.3 6651.9 10:27:22 INFO - PID 2008 | 10:27:22 INFO - PID 2008 | [#1] ext.html Cycles:180 Average:46527.97 Median:184711.74 stddev:83870.94 (45.4%) stddev-sans-first:84035.98 10:27:22 INFO - PID 2008 | Values: 810.4 832.0 841.9 854.8 863.9 37768.0 38609.0 38610.8 38674.8 38743.0 38927.8 38948.8 39060.0 39074.2 39228.6 7556.5 7685.8 7693.5 7697.7 7719.8 6921.4 7572.3 7666.8 7667.9 8307.0 8481.1 8512.4 8513.4 8519.2 8533.4 8446.0 8475.5 8490.7 8497.6 8516.6 8291.6 8310.3 8349.8 8355.4 8396.3 37047.3 37157.4 37189.4 37395.4 37397.6 3611.2 3630.6 3648.0 3652.3 3653.5 30717.8 30746.4 30749.7 30751.7 30913.9 2752.5 3096.4 3152.0 3181.5 3197.2 255664.0 259211.0 259219.0 337495.0 337948.0 49720.8 50177.7 50504.0 50581.5 50649.7 51974.0 52189.7 52376.2 52386.7 52472.7 13049.8 15415.8 15537.8 15605.7 15606.3 12709.7 12771.2 12779.4 12779.9 12782.9 13934.1 14102.4 14129.7 14138.4 14178.9 6165.5 6183.8 6216.8 6235.3 6247.0 910.8 920.8 956.3 994.8 1048.6 22742.0 23403.5 23424.5 23729.5 24522.9 966.6 1063.1 1097.5 1126.8 1178.8 11026.0 12717.8 12728.7 12737.6 12815.3 365770.0 367414.0 368101.2 368541.0 369765.0 55318.7 55804.0 56015.2 56203.7 56308.7 1376.3 1463.9 1547.1 1554.9 1571.2 20054.5 22834.3 22993.9 23333.9 23550.3 39626.6 39658.6 39672.0 39710.8 39724.0 288885.6 288994.0 289263.0 289410.6 290700.0 29952.7 30421.1 30493.2 30534.2 36211.6 43415.0 43758.3 44317.3 44863.8 45352.8 27684.0 27712.4 28372.7 30064.0 30603.8 29618.6 30465.8 31169.1 31225.4 31230.7 30312.7 36806.1 37027.7 37077.0 37114.8 29660.6 29752.0 29892.0 30890.5 31656.8 22604.0 24308.3 24311.8 24520.0 24545.8 10:27:22 INFO - PID 2008 | 10:27:22 INFO - PID 2008 | [#2] jquery.html Cycles:180 Average:39285.54 Median:12382.81 stddev:99188.93 (801.0%) stddev-sans-first:99424.37 10:27:22 INFO - PID 2008 | Values: 471.8 499.0 505.7 511.1 534.1 21005.7 21953.3 22338.6 22511.5 22701.3 24641.3 24678.8 24886.8 25042.7 25045.0 6222.7 6379.6 6391.8 6402.5 6405.8 6275.8 6363.2 6387.8 6392.0 6404.0 5133.7 5653.4 6282.4 6313.0 6329.4 6226.0 6272.7 6291.6 6299.8 6317.6 5758.5 5762.5 5779.2 5806.7 5811.0 26807.9 28712.9 29343.8 29865.9 30616.5 2324.9 2327.9 2350.9 2366.7 2373.6 19260.9 19482.2 19786.1 19822.0 19870.0 1951.6 2177.8 2231.0 2241.9 2254.1 167088.2 174015.0 182514.0 183766.0 187746.0 14295.5 14567.8 15019.2 15095.2 15666.1 20445.9 20476.5 20486.5 20503.2 20503.6 9277.2 10029.7 10127.6 10263.6 10277.5 9094.1 9465.8 9675.4 9717.2 9724.8 7452.6 7605.6 8365.0 9033.8 9127.7 2354.7 2561.1 2593.2 2634.1 2642.7 936.2 937.9 940.8 1009.3 1011.6 19717.6 20048.9 21109.4 21247.5 22007.1 994.0 1001.6 1011.6 1046.2 1046.7 9111.3 10488.7 10756.7 10776.6 11471.7 556995.0 573852.0 583649.0 587227.0 588003.0 132094.3 158805.2 160438.0 163269.2 164267.2 935.3 993.1 1047.2 1130.5 1202.2 22191.3 24178.3 24179.5 24439.0 24482.9 50711.0 66717.0 67792.7 68619.0 68812.0 60643.4 78731.0 88449.0 89590.0 93005.0 11110.7 11467.0 11469.1 11595.5 11616.7 18048.4 18083.5 18148.5 18457.1 18491.4 14482.9 14923.3 15168.7 15234.5 15250.0 10812.7 13439.3 13519.5 13718.4 13858.3 15415.3 15540.9 15657.1 15666.8 15684.7 11268.3 11452.1 11469.9 11473.7 11476.8 13904.6 14645.3 14651.3 14752.2 14953.3 10:27:22 INFO - PID 2008 | 10:27:22 INFO - PID 2008 | [#3] mootools.html Cycles:180 Average:8416.65 Median:2798.48 stddev:5578.78 (199.4%) stddev-sans-first:5570.02 10:27:22 INFO - PID 2008 | Values: 1472.3 1525.8 1535.1 1536.8 1552.3 13481.2 14037.4 14061.6 14086.6 14086.9 11478.3 11793.8 11798.6 11814.1 13616.9 2771.5 2836.3 2837.5 2838.3 2846.7 2759.3 2792.2 2794.8 2797.7 2799.3 2751.2 2779.5 2786.0 2786.1 2790.9 2663.1 2789.4 2849.9 2858.0 2863.7 2527.0 2830.4 2833.9 2854.8 2896.4 13443.7 13814.2 13891.2 13892.9 13908.2 7510.4 7868.3 7885.0 7891.8 7892.8 4687.9 4889.3 4950.1 4960.7 4968.5 1388.0 1419.9 1434.3 1435.9 1436.2 20359.3 20747.5 20819.5 20894.8 20962.4 5465.5 6542.4 6579.6 6608.7 6759.5 14208.1 14292.5 14331.6 14474.9 14502.5 7390.1 7601.8 7633.1 7639.7 7659.9 6945.5 6946.2 7036.6 7066.2 7111.5 5108.7 5242.6 5254.2 5273.3 5283.1 1518.9 1520.7 1529.1 1530.0 1542.3 818.8 819.3 848.0 849.4 866.4 7198.6 7361.2 7454.4 7706.2 8174.6 681.5 699.8 858.4 967.8 1013.8 4320.5 4760.5 4840.0 5276.4 5311.5 17033.6 17322.2 18161.2 18890.4 18901.1 17188.5 18771.5 18933.4 18936.2 19029.2 9148.5 9687.6 9726.3 9819.1 9819.5 7845.8 8185.7 8195.4 8350.9 8828.6 16661.8 17228.5 17422.3 17545.9 17582.3 16557.1 16807.7 17225.4 17981.5 18005.5 10889.6 11101.6 11105.8 11107.6 11119.2 12597.4 12606.0 12611.7 12683.3 12688.2 9864.9 10039.9 10044.5 10288.8 10580.6 9767.6 9824.9 10198.0 10205.5 10233.5 9330.3 9356.4 9425.8 9445.0 9452.7 6583.5 6813.4 6871.2 7045.0 7111.7 6903.2 6966.4 7151.7 7298.6 7300.2 10:27:22 INFO - PID 2008 | 10:27:22 INFO - PID 2008 | [#4] prototype.html Cycles:180 Average:5643.65 Median:6298.99 stddev:1756.34 (27.9%) stddev-sans-first:1716.88 10:27:22 INFO - PID 2008 | Values: 416.5 422.7 433.2 444.3 466.2 5225.7 6492.0 6521.3 6619.9 6670.7 6235.3 6387.9 6471.4 6472.5 6484.9 4609.7 4815.9 4826.7 4830.2 4853.9 4030.3 4234.6 4803.4 4807.4 4851.3 4616.7 4790.8 4805.6 4815.6 4827.8 4787.9 4791.2 4795.2 4806.3 4838.8 4202.7 4206.3 4238.6 4241.9 4248.2 5470.6 6226.6 6236.8 6245.8 6249.7 5513.7 5721.4 5737.3 5747.9 5749.4 5464.3 6912.4 6935.3 6951.2 6957.3 3911.3 4842.5 4852.7 4858.9 4868.4 6198.3 7314.5 7332.4 7358.4 7380.7 4278.4 4948.6 4963.8 4971.7 4978.9 5026.7 5036.6 5049.4 5050.4 5057.5 4718.5 5164.5 5725.6 5740.7 5756.3 7046.0 7053.8 7060.7 7089.5 7096.6 1464.8 1586.9 1675.8 1712.1 1729.7 939.5 1015.3 1063.8 1140.7 1170.5 6362.7 6369.7 6473.2 6520.8 6527.8 6835.5 7223.0 7243.5 7261.5 7272.7 6069.9 6213.5 6440.5 6484.2 6522.7 3175.3 3250.3 3339.4 3609.2 3769.2 6248.6 7384.7 7399.9 7428.2 7437.7 6880.6 7413.7 7417.3 7422.7 7466.3 6819.8 7365.5 7396.1 7403.3 7423.9 6348.3 7169.5 7185.9 7193.1 7216.4 6531.2 6589.8 6802.1 7099.4 7142.6 6684.5 6770.3 6816.8 7275.1 7492.2 4622.9 4842.8 5125.6 5483.2 5553.5 6129.9 7260.8 7265.2 7269.3 7299.9 6394.0 6926.7 6952.1 6964.7 6970.8 5737.3 6361.9 6941.0 6952.9 6961.8 6049.9 6734.3 6818.6 6847.8 6869.8 6925.9 6940.4 6947.0 6980.6 6986.5 5571.6 5958.9 6713.8 6734.5 6778.3 10:27:22 INFO - PID 2008 | 10:27:22 INFO - PID 2008 | [#5] yui.html Cycles:180 Average:25783.39 Median:18246.36 stddev:39776.64 (218.0%) stddev-sans-first:39843.94 10:27:22 INFO - PID 2008 | Values: 784.4 816.8 820.7 844.6 848.3 15146.9 16903.3 16985.2 17060.8 17163.6 15439.9 17962.5 18616.8 18640.5 18661.0 969.2 991.2 991.4 991.4 992.7 981.0 989.4 989.4 989.5 991.1 1036.1 1037.7 1051.4 1051.6 1052.5 1031.0 1032.3 1040.5 1040.5 1048.4 741.9 749.3 753.7 755.6 755.6 17269.9 17521.9 17530.2 17534.9 17617.6 16955.6 18248.8 18456.5 18482.4 18544.4 14425.1 14849.7 15039.8 15138.0 15185.7 3530.1 3550.7 3553.0 3583.4 3598.7 83392.0 148481.5 161530.4 162040.0 164644.0 13774.2 13961.8 14053.2 14125.3 14128.0 26254.5 26365.0 26701.7 26821.6 26941.7 8132.6 8159.9 8204.0 8205.4 8231.7 4108.5 4113.9 4165.2 4166.1 4174.0 4079.3 4696.0 4725.8 4756.6 4804.0 1025.7 1044.1 1048.4 1053.8 1054.1 536.5 560.3 642.6 672.8 676.4 11410.9 12143.7 12368.3 12503.9 12590.2 613.0 638.1 643.3 661.0 662.0 5166.7 5483.6 5648.2 5823.6 5909.1 141691.6 146412.0 153836.7 156346.9 160312.0 113748.3 113775.0 113842.4 125966.0 133225.3 94180.6 99105.0 100598.0 105299.5 107006.0 11770.2 12714.9 12731.0 12968.5 13007.5 45775.3 46061.6 46693.0 47927.5 49078.0 62281.0 62418.1 63495.4 63709.0 66202.7 17165.6 17537.5 18184.2 18261.7 18283.3 18381.4 18680.6 18859.5 19081.9 19135.8 17147.0 17973.4 17975.4 18243.9 18296.6 17324.0 18021.6 18217.3 18238.0 18627.4 16925.2 17439.4 17470.5 17499.6 17944.9 11454.8 11577.3 12162.8 12424.1 12507.2 12362.9 12990.4 13698.5 14423.4 14429.3 10:27:22 INFO - PID 2008 | -------- Summary: end -------- 10:27:22 INFO - PID 2008 | 10:27:22 INFO - TEST-INFO | 2008: exit 0 10:27:22 INFO - TEST-OK | dromaeo_css | took 1142995ms 10:27:22 INFO - TEST-START | kraken 10:27:22 INFO - operating with platform_type : mac_ 10:27:22 INFO - Initialising browser for kraken test... 10:27:22 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49277/getInfo.html -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpxfxpXB/profile 10:27:22 INFO - TEST-INFO | started process 2018 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49277/getInfo.html -foreground) 10:27:55 INFO - TEST-INFO | 2018: killed by SIGTERM 10:27:55 INFO - Browser initialized. 10:27:55 INFO - Running cycle 1/1 for kraken test... 10:27:55 INFO - TEST-INFO | started process 2022 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpxfxpXB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:27:57 INFO - PID 2022 | 2017-06-13 10:27:57.627 plugin-container[2023:11840] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9543, name = 'com.apple.tsm.portname' 10:27:57 INFO - PID 2022 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:27:57 INFO - PID 2022 | 2017-06-13 10:27:57.628 plugin-container[2023:11840] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9727, name = 'com.apple.CFPasteboardClient' 10:27:57 INFO - PID 2022 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:27:57 INFO - PID 2022 | 2017-06-13 10:27:57.628 plugin-container[2023:11840] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:27:58 INFO - PID 2022 | 2017-06-13 10:27:58.429 plugin-container[2024:11885] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 10:27:58 INFO - PID 2022 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:27:58 INFO - PID 2022 | 2017-06-13 10:27:58.429 plugin-container[2024:11885] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9523, name = 'com.apple.CFPasteboardClient' 10:27:58 INFO - PID 2022 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:27:58 INFO - PID 2022 | 2017-06-13 10:27:58.429 plugin-container[2024:11885] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:28:07 INFO - PID 2022 | RSS: Main: 214757376 10:28:07 INFO - PID 2022 | 10:28:10 INFO - PID 2022 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 10:28:11 INFO - PID 2022 | RSS: Main: 228671488 10:28:11 INFO - PID 2022 | 10:28:14 INFO - PID 2022 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 10:28:14 INFO - PID 2022 | RSS: Main: 239165440 10:28:14 INFO - PID 2022 | 10:28:17 INFO - PID 2022 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 10:28:18 INFO - PID 2022 | RSS: Main: 242704384 10:28:18 INFO - PID 2022 | 10:28:20 INFO - PID 2022 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 10:28:21 INFO - PID 2022 | RSS: Main: 244973568 10:28:21 INFO - PID 2022 | 10:28:23 INFO - PID 2022 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:28:23 INFO - PID 2022 | RSS: Main: 245907456 10:28:23 INFO - PID 2022 | 10:28:26 INFO - PID 2022 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 10:28:27 INFO - PID 2022 | RSS: Main: 249769984 10:28:27 INFO - PID 2022 | 10:28:30 INFO - PID 2022 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 10:28:30 INFO - PID 2022 | RSS: Main: 250040320 10:28:30 INFO - PID 2022 | 10:28:33 INFO - PID 2022 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 10:28:34 INFO - PID 2022 | RSS: Main: 250675200 10:28:34 INFO - PID 2022 | 10:28:35 INFO - PID 2022 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:28:36 INFO - PID 2022 | RSS: Main: 249425920 10:28:36 INFO - PID 2022 | 10:28:38 INFO - PID 2022 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:28:38 INFO - PID 2022 | RSS: Main: 249405440 10:28:38 INFO - PID 2022 | 10:28:40 INFO - PID 2022 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:28:41 INFO - PID 2022 | RSS: Main: 249458688 10:28:41 INFO - PID 2022 | 10:28:44 INFO - PID 2022 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:28:44 INFO - PID 2022 | RSS: Main: 249552896 10:28:44 INFO - PID 2022 | 10:28:48 INFO - PID 2022 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:28:48 INFO - PID 2022 | RSS: Main: 249806848 10:28:48 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 10:28:50 INFO - PID 2022 | RSS: Main: 251432960 10:28:50 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | __start_tp_report 10:28:50 INFO - PID 2022 | _x_x_mozilla_page_load 10:28:50 INFO - PID 2022 | _x_x_mozilla_page_load_details 10:28:50 INFO - PID 2022 | |i|pagename|runs| 10:28:50 INFO - PID 2022 | |0;ai-astar;103;113;145;134;160;100;163;147;167;119 10:28:50 INFO - PID 2022 | |1;audio-beat-detection;137;108;139;140;106;129;110;139;139;112 10:28:50 INFO - PID 2022 | |2;audio-dft;195;201;203;202;195;195;198;199;202;195 10:28:50 INFO - PID 2022 | |3;audio-fft;65;78;81;153;61;57;73;82;162;58 10:28:50 INFO - PID 2022 | |4;audio-oscillator;67;67;122;69;69;72;107;71;69;113 10:28:50 INFO - PID 2022 | |5;imaging-gaussian-blur;91;91;90;90;90;91;89;89;91;94 10:28:50 INFO - PID 2022 | |6;imaging-darkroom;97;97;98;97;100;98;97;105;96;98 10:28:50 INFO - PID 2022 | |7;imaging-desaturate;110;137;89;93;92;92;95;90;89;94 10:28:50 INFO - PID 2022 | |8;json-parse-financial;48;48;48;49;52;53;53;53;51;51 10:28:50 INFO - PID 2022 | |9;json-stringify-tinderbox;56;53;55;55;63;61;57;56;55;60 10:28:50 INFO - PID 2022 | |10;stanford-crypto-aes;80;73;79;82;78;78;79;80;81;78 10:28:50 INFO - PID 2022 | |11;stanford-crypto-ccm;142;145;134;122;153;151;164;151;141;175 10:28:50 INFO - PID 2022 | |12;stanford-crypto-pbkdf2;186;189;155;217;226;186;186;218;209;177 10:28:50 INFO - PID 2022 | |13;stanford-crypto-sha256-iterative;65;63;64;63;67;66;81;92;56;73 10:28:50 INFO - PID 2022 | __end_tp_report 10:28:50 INFO - PID 2022 | __start_cc_report 10:28:50 INFO - PID 2022 | _x_x_mozilla_cycle_collect,1024 10:28:50 INFO - PID 2022 | __end_cc_report 10:28:50 INFO - PID 2022 | __startTimestamp1497374930583__endTimestamp 10:28:50 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | ------- Summary: start ------- 10:28:50 INFO - PID 2022 | Number of tests: 14 10:28:50 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | [#0] ai-astar Cycles:10 Average:135.10 Median:146.00 stddev:25.09 (17.2%) stddev-sans-first:23.77 10:28:50 INFO - PID 2022 | Values: 103.0 113.0 145.0 134.0 160.0 100.0 163.0 147.0 167.0 119.0 10:28:50 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | [#1] audio-beat-detection Cycles:10 Average:125.90 Median:138.00 stddev:14.94 (10.8%) stddev-sans-first:15.30 10:28:50 INFO - PID 2022 | Values: 137.0 108.0 139.0 140.0 106.0 129.0 110.0 139.0 139.0 112.0 10:28:50 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | [#2] audio-dft Cycles:10 Average:198.50 Median:200.00 stddev:3.34 (1.7%) stddev-sans-first:3.30 10:28:50 INFO - PID 2022 | Values: 195.0 201.0 203.0 202.0 195.0 195.0 198.0 199.0 202.0 195.0 10:28:50 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | [#3] audio-fft Cycles:10 Average:87.00 Median:69.00 stddev:38.33 (55.5%) stddev-sans-first:39.82 10:28:50 INFO - PID 2022 | Values: 65.0 78.0 81.0 153.0 61.0 57.0 73.0 82.0 162.0 58.0 10:28:50 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | [#4] audio-oscillator Cycles:10 Average:82.60 Median:69.00 stddev:22.01 (31.9%) stddev-sans-first:22.61 10:28:50 INFO - PID 2022 | Values: 67.0 67.0 122.0 69.0 69.0 72.0 107.0 71.0 69.0 113.0 10:28:50 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | [#5] imaging-gaussian-blur Cycles:10 Average:90.60 Median:91.00 stddev:1.43 (1.6%) stddev-sans-first:1.51 10:28:50 INFO - PID 2022 | Values: 91.0 91.0 90.0 90.0 90.0 91.0 89.0 89.0 91.0 94.0 10:28:50 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | [#6] imaging-darkroom Cycles:10 Average:98.30 Median:97.00 stddev:2.58 (2.7%) stddev-sans-first:2.70 10:28:50 INFO - PID 2022 | Values: 97.0 97.0 98.0 97.0 100.0 98.0 97.0 105.0 96.0 98.0 10:28:50 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | [#7] imaging-desaturate Cycles:10 Average:98.10 Median:92.00 stddev:14.96 (16.3%) stddev-sans-first:15.23 10:28:50 INFO - PID 2022 | Values: 110.0 137.0 89.0 93.0 92.0 92.0 95.0 90.0 89.0 94.0 10:28:50 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.50 stddev:2.17 (4.2%) stddev-sans-first:2.09 10:28:50 INFO - PID 2022 | Values: 48.0 48.0 48.0 49.0 52.0 53.0 53.0 53.0 51.0 51.0 10:28:50 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | [#9] json-stringify-tinderbox Cycles:10 Average:57.10 Median:56.50 stddev:3.18 (5.6%) stddev-sans-first:3.35 10:28:50 INFO - PID 2022 | Values: 56.0 53.0 55.0 55.0 63.0 61.0 57.0 56.0 55.0 60.0 10:28:50 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:79.50 stddev:2.44 (3.1%) stddev-sans-first:2.55 10:28:50 INFO - PID 2022 | Values: 80.0 73.0 79.0 82.0 78.0 78.0 79.0 80.0 81.0 78.0 10:28:50 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:151.00 stddev:14.88 (9.9%) stddev-sans-first:15.64 10:28:50 INFO - PID 2022 | Values: 142.0 145.0 134.0 122.0 153.0 151.0 164.0 151.0 141.0 175.0 10:28:50 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.90 Median:199.00 stddev:22.04 (11.1%) stddev-sans-first:23.14 10:28:50 INFO - PID 2022 | Values: 186.0 189.0 155.0 217.0 226.0 186.0 186.0 218.0 209.0 177.0 10:28:50 INFO - PID 2022 | 10:28:50 INFO - PID 2022 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:66.50 stddev:10.46 (15.7%) stddev-sans-first:10.99 10:28:50 INFO - PID 2022 | Values: 65.0 63.0 64.0 63.0 67.0 66.0 81.0 92.0 56.0 73.0 10:28:50 INFO - PID 2022 | -------- Summary: end -------- 10:28:50 INFO - PID 2022 | 10:28:51 INFO - TEST-INFO | 2022: exit 0 10:28:51 INFO - TEST-OK | kraken | took 88659ms 10:28:51 INFO - SUITE-END | took 1231s 10:28:51 INFO - Completed test suite (00:20:32) 10:28:51 INFO - javascript benchmark 10:28:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8435.789185312295, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2983.7314641682246, 3086.475299199136, 3672.5042119313903, 3715.962840371597, 3722.0694826293425, 5246.317978662773, 5410.62125651204, 6316.115743795872, 6372.681365931708, 6441.6779161041995, 6358.968205158968, 6393.0730044143575, 6415.358464153583, 6423.293437721847, 6433.839154021151, 5550.111982082867, 5600.355959064719, 5616.410276920926, 5661.377248502682, 5726.226959360475, 5452.291202143716, 5587.888242235153, 5643.746031428595, 5656.349519805213, 5689.317281926154, 5489.066858634042, 5508.531774799147, 5559.499645031946, 5568.025595520768, 5582.190582365575, 5602.299712535933, 5616.382197958222, 5627.8593035174035, 5632.295963004624, 5664.716764161817, 5476.698781567012, 5567.944320556783, 5597.104463285855, 5603.943960560383, 5638.661680299195, 6287.1198032275515, 6367.6179429233935, 6375.203099612549, 6376.29860715321, 6395.808125756234, 6666.533342666015, 6752.898706519357, 6879.380855723009, 6913.1358580177475, 6951.16586009682, 6853.314668533157, 6883.27725588811, 6931.445484361239, 6986.40615547679, 7013.544119632259, 6410.8717825643225, 6470.352964703586, 6536.477081833537, 6536.8365790855605, 6544.44372228366, 6028.186194863725, 7179.676914538858, 7180.748673796392, 7185.784426467214, 7199.856002879913, 6565.901511477332, 6674.799756007327, 6677.0, 6680.966595166993, 6694.531382803157, 6820.147481564804, 6853.143357080365, 6858.759943401957, 6951.44388448933, 7013.89479157813, 6843.452523798084, 6851.109355783717, 6868.381845633917, 6896.586204827727, 6913.688884000232, 6875.346842049998, 6885.758998435131, 6891.414229790524, 6915.619640919797, 6926.099607051051, 5378.327709036371, 5410.702411367412, 5419.729013549307, 5423.105187644073, 5453.727313634303, 4251.957480425218, 4834.564889159992, 4893.559579637778, 4960.801567937242, 4990.850274491771, 5978.701064946736, 6304.936950630435, 6513.088167656532, 6529.902051469232, 6539.673016349164, 7015.89476157858, 7038.92961070393, 7044.577325360392, 7046.577205367695, 7072.646367681596, 5441.265429167091, 6492.67536623167, 6511.413972742381, 6513.12072870166, 6550.639714815636, 5966.820995370146, 6059.394060593905, 6068.423499767515, 6104.847378815476, 6131.540134489931, 6966.094407726861, 7022.824429389204, 7035.155781306072, 7038.2609825969585, 7048.894266586005, 6113.786017489367, 6185.876282474235, 6912.585244885323, 6996.545224560387, 7026.367626913602, 7200.603966781878, 7209.71161153548, 7215.206327304098, 7229.240929702306, 7242.710291588278, 5699.658020518782, 6922.723091076301, 6931.133608298963, 6935.75724849648, 6965.5124141309625, 7009.719611215494, 7067.82330441743, 7068.187158476841, 7074.0, 7075.469339799432, 5593.214355063586, 6541.215054193527, 6639.734410623521, 6713.160854893138, 6808.795736127924, 6421.935780642134, 6784.660766961533, 6798.932010680028, 6818.93181068183, 6837.418819400406, 6408.423241908251, 6416.422521972858, 6529.053287273414, 6533.771318003847, 6691.5650482718465, 6612.933870661232, 6647.833804154935, 6662.034005069335, 6718.697658605277, 6786.117804685359, 6463.741450341934, 6630.972199309115, 6638.435732962559, 6644.269130395749, 6651.900221496779], "value": 6255.792997981253, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [810.3800592546703, 831.9667213311468, 841.8821365008898, 854.8162145138793, 863.9265662418694, 37768.0, 38609.03477413066, 38610.8069459653, 38674.8066259669, 38743.0, 38927.80536097322, 38948.83153505399, 39060.0, 39074.21851562967, 39228.60771392285, 7556.5466072035715, 7685.846283074336, 7693.499922505047, 7697.730579429721, 7719.768406947801, 6921.377076063153, 7572.318491335779, 7666.769996900102, 7667.923320766805, 8306.958465207665, 8481.067082620906, 8512.404131710782, 8513.404061715652, 8519.190676885686, 8533.359998000144, 8445.957770211171, 8475.49147051179, 8490.66037358505, 8497.597896347095, 8516.574171291442, 8291.585420729, 8310.293625041879, 8349.8330033399, 8355.373346998938, 8396.328293736486, 37047.25905481902, 37157.442638360444, 37189.442158367645, 37395.439068413994, 37397.626023739685, 3611.1513794258326, 3630.6369363063745, 3648.0, 3652.3243200008083, 3653.4702468141973, 30717.846410768027, 30746.385072298654, 30749.69250307513, 30751.692483075105, 30913.918012869442, 2752.545829938058, 3096.411681780477, 3151.952720709191, 3181.5068664357063, 3197.232664160608, 255663.99999999814, 259211.0, 259219.0, 337495.0, 337948.0, 49720.756981075036, 50177.74911125421, 50503.98992020139, 50581.494185058415, 50649.73375665638, 51974.0, 52189.69525761886, 52376.21435678468, 52386.73806631019, 52472.737636311576, 13049.804252936214, 15415.768763468557, 15537.84462155364, 15605.68788624221, 15606.297716602554, 12709.745805083847, 12771.169873958162, 12779.424925878358, 12779.872201278054, 12782.872171278168, 13934.094283871718, 14102.435902563988, 14129.717405651829, 14138.363773630212, 14178.929105354408, 6165.537584681167, 6183.84540386494, 6216.7824126155365, 6235.3452887446165, 6247.0, 910.7905181808221, 920.8250432417819, 956.2780101023683, 994.8060128275062, 1048.554364395129, 22742.0, 23403.53192936166, 23424.531509369717, 23729.525409491715, 24522.87738561296, 966.6133546581424, 1063.1441689440076, 1097.4567589043475, 1126.785732013628, 1178.776032553812, 11026.0, 12717.809232861515, 12728.745425091447, 12737.617871463872, 12815.29515876617, 365770.0, 367414.0, 368101.15949420084, 368541.0, 369765.0, 55318.72340638351, 55804.0, 56015.159772603445, 56203.71898140483, 56308.718456407456, 1376.3049659921676, 1463.8902082343866, 1547.0794877048363, 1554.8911576189785, 1571.2143928035982, 20054.49863753418, 22834.31497055091, 22993.88503057474, 23333.883330583238, 23550.29349119529, 39626.60373396229, 39658.60341396549, 39672.00819979524, 39710.80144599374, 39724.006899827735, 288885.55557222076, 288994.0, 289263.0, 289410.5529472339, 290700.0, 29952.70047299499, 30421.087367379016, 30493.23766905845, 30534.236644084074, 36211.63788362188, 43415.0, 43758.343624845016, 44317.33523997207, 44863.775681122694, 45352.77323613361, 27684.0, 27712.44575108527, 28372.716272837006, 30064.0, 30603.846980764953, 29618.55572166376, 30465.847670761505, 31169.064928052194, 31225.37549249048, 31230.687693123687, 30312.69687303099, 36806.05551555923, 37027.70403035881, 37077.0, 37114.81442592878, 29660.555091674072, 29752.0, 29892.0, 30890.53664194994, 31656.841715791274, 22604.0, 24308.270751877473, 24311.83263129999, 24520.0, 24545.754542454346], "value": 17519.49032739994, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.84429138384337, 498.96215870988345, 505.7319620601083, 511.0775051032887, 534.1267028408556, 21005.68491472629, 21953.341399757992, 22338.553228935412, 22511.54976900461, 22701.318960431214, 24641.26076217712, 24678.75321246787, 24886.751132488716, 25042.749572504315, 25045.0, 6222.7199776010075, 6379.5853269537565, 6391.808245752635, 6402.519811014169, 6405.807825765234, 6275.843103922392, 6363.236411630611, 6387.840303992414, 6392.041193820917, 6404.039394090877, 5133.691978481284, 5653.434656534355, 6282.371762823726, 6312.989921612543, 6329.398707122816, 6225.968870155642, 6272.717727702265, 6291.591046581958, 6299.811005669837, 6317.6209427434505, 5758.481736643722, 5762.4525670061275, 5779.190913272145, 5806.680632565207, 5810.970945145247, 26807.86596067007, 28712.856435717687, 29343.826246950095, 29865.850670746724, 30616.54075188874, 2324.9186278480342, 2327.918522851709, 2350.8589484630807, 2366.6568347589505, 2373.6320870265135, 19260.903695481433, 19482.220711171536, 19786.109625066907, 19822.008899554963, 19870.0, 1951.6291904538093, 2177.7822217778094, 2230.955380892373, 2241.8766967816764, 2254.0645632062674, 167088.16455917765, 174015.0, 182514.0, 183766.0, 187745.99999999863, 14295.499657511937, 14567.781483277759, 15019.24903754808, 15095.245237738069, 15666.138362390178, 20445.897770511052, 20476.488087797923, 20486.487837803877, 20503.17987280522, 20503.58992820165, 9277.211437027929, 10029.69910902674, 10127.59489620407, 10263.58945642166, 10277.537510812032, 9094.090590940854, 9465.763355916019, 9675.419474831391, 9717.22262219035, 9724.805503889882, 7452.552846829208, 7605.581693006938, 8364.958175209207, 9033.77415564616, 9127.68053118151, 2354.682117914094, 2561.052410608087, 2593.196109206152, 2634.1175706138274, 2642.748938850806, 936.194872409727, 937.8780758501351, 940.849464085743, 1009.3136667066318, 1011.5953618552638, 19717.60564788696, 20048.89975550113, 21109.366718998455, 21247.468813279793, 22007.11971521153, 993.9701808945744, 1001.6494227020485, 1011.595361855249, 1046.1892033673812, 1046.6650671785108, 9111.316651251183, 10488.68533943983, 10756.677299681021, 10776.568937242422, 11471.713207169887, 556995.0, 573852.0, 583649.0, 587227.0, 588003.0, 132094.33952830173, 158805.2059739694, 160438.0, 163269.18365408096, 164267.17866410592, 935.2985261054209, 993.1111655068673, 1047.177965297238, 1130.5477808876515, 1202.1825158891863, 22191.334259972227, 24178.274651760476, 24179.51640967206, 24439.02243910224, 24482.877585611957, 50710.98578028493, 66717.0, 67792.6610366945, 68619.0, 68812.0, 60643.39356606377, 78731.0, 88449.0, 89590.0, 93005.0, 11110.722231944266, 11467.0, 11469.082473402275, 11595.478203480694, 11616.709582260512, 18048.368307109187, 18083.457496275132, 18148.455546333633, 18457.077146142907, 18491.44525664232, 14482.855171448573, 14923.253837308308, 15168.696626067638, 15234.542963711106, 15250.0, 10812.67561973142, 13439.328033598085, 13519.459221631025, 13718.38267277955, 13858.307084645929, 15415.306311216247, 15540.922295388451, 15657.138857362954, 15666.764998524803, 15684.686306273583, 11268.323900565993, 11452.08383329315, 11469.885301147215, 11473.655790326304, 11476.770464590829, 13904.58286251414, 14645.340959657055, 14651.267436628339, 14752.188629625474, 14953.25233738287], "value": 11615.024272528191, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1472.3080152328405, 1525.7863899054141, 1535.0559405965328, 1536.77716731074, 1552.3169805285677, 13481.191128532282, 14037.368318425682, 14061.578152655436, 14086.64783380416, 14086.943479239073, 11478.253913495617, 11793.823092653596, 11798.58704945327, 11814.113941454403, 13616.863831361683, 2771.459565384746, 2836.276749428893, 2837.489251934651, 2838.3188034871687, 2846.7437930586243, 2759.2550011496983, 2792.176307989138, 2794.804363694542, 2797.6642802863685, 2799.286182023581, 2751.2159034675124, 2779.499690055789, 2786.0, 2786.1362977477047, 2790.9162725118176, 2663.1344812935777, 2789.442111577682, 2849.8575071246564, 2857.9997001049464, 2863.727945845141, 2527.0018342754693, 2830.391465834835, 2833.914982550527, 2854.771618270554, 2896.3772788850288, 13443.731125377437, 13814.240216788072, 13891.166530008131, 13892.93053534736, 13908.235047072407, 7510.436717246228, 7868.252516011027, 7884.960575197088, 7891.842163156763, 7892.842143157105, 4687.88280292992, 4889.29105279736, 4950.0842344166085, 4960.6527543072, 4968.527989840959, 1387.9653008674763, 1419.8793102586292, 1434.26852305324, 1435.899487035908, 1436.1526699247495, 20359.287424940205, 20747.481312967295, 20819.479513012146, 20894.791052089437, 20962.371128866005, 5465.508104270555, 6542.443892269115, 6579.638119903356, 6608.669566521751, 6759.526833121634, 14208.076475029296, 14292.49976250847, 14331.570052898429, 14474.927625362016, 14502.492412765503, 7390.076240469942, 7601.7719468416035, 7633.084029916335, 7639.656215470317, 7659.9234007660325, 6945.506716056072, 6946.16646002483, 7036.577805331696, 7066.222715501291, 7111.53775004623, 5108.66793658411, 5242.606804489602, 5254.211868219798, 5273.288106105703, 5283.128283833202, 1518.916459594711, 1520.7490764023812, 1529.07490967965, 1529.9541013769606, 1542.2674229740783, 818.7841056031788, 819.3445243804932, 848.0, 849.3757088539918, 866.432486721186, 7198.640067996579, 7361.190269070398, 7454.40364770817, 7706.229377062361, 8174.550399728072, 681.4514315975687, 699.7585832887646, 858.3948316436897, 967.7870868408938, 1013.8428543575758, 4320.503142138631, 4760.500147484534, 4839.9758001210475, 5276.419593844674, 5311.548518375904, 17033.63730901525, 17322.22050007753, 18161.182746776427, 18890.43328700141, 18901.14944827486, 17188.52703913943, 18771.530711732044, 18933.431997040112, 18936.24255029811, 19029.238830446902, 9148.451092934445, 9687.612495500101, 9726.319157658896, 9819.06718861707, 9819.509024548744, 7845.803854903673, 8185.690289553643, 8195.42632015777, 8350.916490835014, 8828.558572071499, 16661.750073749146, 17228.483145505656, 17422.303107875545, 17545.912270438566, 17582.296708131533, 16557.089360085312, 16807.747883781998, 17225.39711110105, 17981.460556183334, 18005.549861253574, 10889.564417423215, 11101.611443599435, 11105.777884442105, 11107.611233606785, 11119.16606254518, 12597.370131493572, 12606.0, 12611.684707882376, 12683.302418367079, 12688.23870567769, 9864.85202721974, 10039.949800250952, 10044.54799534008, 10288.845667315147, 10580.576776928836, 9767.56045977947, 9824.901750982684, 10198.0, 10205.489725513546, 10233.539490723084, 9330.253579713743, 9356.391834531007, 9425.764355890884, 9444.961054283885, 9452.716418507456, 6583.473322134122, 6813.386795188457, 6871.244163142151, 7045.0, 7111.684338397413, 6903.240643529308, 6966.373026427443, 7151.678174482265, 7298.56208627484, 7300.196978332486], "value": 6218.607490809432, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.46484267715994, 422.7167797575624, 433.20939285803416, 444.30909935051005, 466.1655636410825, 5225.712585807779, 6492.026196070586, 6521.347865213482, 6619.900701489481, 6670.733170673179, 6235.261201486868, 6387.882120628883, 6471.4175724184815, 6472.546921715481, 6484.870302593957, 4609.723416595007, 4815.855524334275, 4826.686265392756, 4830.154722923492, 4853.878653033667, 4030.334994725881, 4234.618884300412, 4803.375561177059, 4807.35100761398, 4851.320815085891, 4616.699914505546, 4790.832320868771, 4805.639577031719, 4815.566599006089, 4827.782749776251, 4787.904241915177, 4791.209450440673, 4795.160846851804, 4806.327114204014, 4838.782254798542, 4202.747835129902, 4206.348016057516, 4238.597333253336, 4241.851535196253, 4248.214080395137, 5470.617056806025, 6226.564140510166, 6236.750529978841, 6245.781397651061, 6249.656268905208, 5513.669179849222, 5721.370649228582, 5737.254156959569, 5747.856303592402, 5749.425057494259, 5464.3442786865835, 6912.377885990235, 6935.33551947534, 6951.200611929592, 6957.339052790027, 3911.2764138634443, 4842.539958703917, 4852.733099679516, 4858.902821943577, 4868.367112275381, 6198.287196972361, 7314.500527391925, 7332.413406927433, 7358.374538164262, 7380.704771809121, 4278.358246263023, 4948.628852836052, 4963.751812409365, 4971.676841005323, 4978.90042199154, 5026.723530205801, 5036.622253331015, 5049.419316778544, 5050.419201791768, 5057.519535644108, 4718.504557021532, 5164.535191832753, 5725.570582206351, 5740.727785162418, 5756.251687280626, 7045.964770176116, 7053.753118640823, 7060.717571297193, 7089.468289878279, 7096.609686467191, 1464.8242210934543, 1586.8730501559849, 1675.798904131512, 1712.1268153241936, 1729.6886560419246, 939.5208443693629, 1015.3451024089569, 1063.7766568446348, 1140.7433327501246, 1170.5259369955181, 6362.713677884506, 6369.71336289868, 6473.158489396443, 6520.804375868732, 6527.771527996592, 6835.521513494107, 7223.0, 7243.529170603894, 7261.455390845707, 7272.69091563513, 6069.907416664954, 6213.502919766498, 6440.549161558646, 6484.221893372825, 6522.739090436425, 3175.285560748813, 3250.3499300140065, 3339.382214290364, 3609.242059167604, 3769.151940813295, 6248.562600617913, 7384.704611815575, 7399.926000740032, 7428.182899881009, 7437.702491900372, 6880.552764070319, 7413.666385012687, 7417.258274172647, 7422.665980030912, 7466.253374662599, 6819.761308354184, 7365.521241119417, 7396.149442814038, 7403.259674032769, 7423.888641670487, 6348.317695810706, 7169.4981351304905, 7185.856282874418, 7193.100862392201, 7216.386607138452, 6531.216254049545, 6589.769358072444, 6802.081718968074, 7099.36105750485, 7142.571445713066, 6684.532082754161, 6770.2891196425635, 6816.795496135124, 7275.090613673291, 7492.175860655432, 4622.907541849211, 4842.7820748065715, 5125.615578831677, 5483.204935284281, 5553.52831499661, 6129.908051379144, 7260.782176534713, 7265.164506081868, 7269.345758881726, 7299.890501642586, 6394.0, 6926.653667316513, 6952.130983627047, 6964.676711424813, 6970.756023539152, 5737.254156959569, 6361.8727625448155, 6940.9652951734915, 6952.860942781217, 6961.756338528127, 6049.939500604937, 6734.292899245509, 6818.624975626388, 6847.828804279933, 6869.828254293682, 6925.89611155823, 6940.375366217065, 6947.0, 6980.581165130108, 6986.545874518343, 5571.607098225443, 5958.910616340671, 6713.791517526892, 6734.494912881455, 6778.288279730557], "value": 5095.615531017096, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.4077721320402, 816.7590560784569, 820.7004443378165, 844.5945945945946, 848.3255811629751, 15146.924265378671, 16903.323867045303, 16985.150742462858, 17060.82939170608, 17163.570910727238, 15439.922800385983, 17962.461126166236, 18616.813831861677, 18640.533986650342, 18660.973646449438, 969.1955676788249, 991.1624677147835, 991.3655260633201, 991.4101109839634, 992.7468495533665, 981.0287815063072, 989.3519744567284, 989.3915242126077, 989.529973262698, 991.1377101921303, 1036.0934182590233, 1037.667946257198, 1051.4217180550704, 1051.55834549489, 1052.4737631184407, 1031.0050800977056, 1032.2722480651123, 1040.4589613401026, 1040.5421614489637, 1048.3657387280662, 741.9220981796941, 749.34806718155, 753.7060546386914, 755.5806527377304, 755.5957562703927, 17269.913650431794, 17521.912390438094, 17530.21114049858, 17534.947903125852, 17617.647647047117, 16955.576110597332, 18248.817511824844, 18456.53858653531, 18482.445526634223, 18544.350947716764, 14425.06237094596, 14849.70300593982, 15039.849601503955, 15138.016028958082, 15185.696286074328, 3530.135116896377, 3550.680438760524, 3553.022918697379, 3583.4445660922597, 3598.712103031751, 83391.99999999939, 148481.51518484784, 161530.3846961527, 162040.0, 164644.0, 13774.242416666979, 13961.79057314141, 14053.227072510905, 14125.293735313193, 14128.0, 26254.474910502064, 26365.0, 26701.732982669924, 26821.597676034875, 26941.730582694316, 8132.552709601029, 8159.918400816035, 8204.0, 8205.425620206648, 8231.711890083938, 4108.465899433114, 4113.876583702494, 4165.166966606691, 4166.0626359068965, 4173.9582604173565, 4079.265732168238, 4695.976520117378, 4725.810967561328, 4756.595689366373, 4803.975980120077, 1025.74869157057, 1044.0864243786687, 1048.3762161513887, 1053.7734387106655, 1054.1356088007913, 536.5251752199332, 560.3192121572309, 642.6465444005778, 672.8082496488576, 676.3642176354211, 11410.94294528522, 12143.696407589705, 12368.257904525757, 12503.937480312541, 12590.24458532491, 612.9785457508966, 638.0939066525544, 643.2602507116853, 660.9669516524154, 661.9569727967666, 5166.689998600096, 5483.588730845203, 5648.181013753065, 5823.621464604787, 5909.113632955091, 141691.58308416783, 146412.0, 153836.69244961336, 156346.8730625381, 160312.0, 113748.29377559343, 113774.99999999834, 113842.43078784553, 125966.0, 133225.33387333, 94180.64548386345, 99105.0, 100597.99402006033, 105299.473502632, 107006.0, 11770.234934729215, 12714.87285127137, 12730.981521478445, 12968.481260749464, 13007.479700811862, 45775.313370298805, 46061.61815145551, 46693.0, 47927.5207247923, 49078.0, 62281.0, 62418.06372904501, 63495.36504634895, 63709.0, 66202.67594648176, 17165.570860728083, 17537.47387578324, 18184.181711822734, 18261.726074109163, 18283.26866925308, 18381.448556543324, 18680.626387471904, 18859.528511787314, 19081.90459047696, 19135.8086419134, 17146.971181729135, 17973.37093201732, 17975.370862019765, 18243.90878045601, 18296.63406731831, 17324.0, 18021.639567208844, 18217.271309147487, 18238.0, 18627.441176764718, 16925.238364273882, 17439.389621363698, 17470.47588572345, 17499.56251093733, 17944.91027544854, 11454.770904582028, 11577.305361677989, 12162.817557736464, 12424.06819488524, 12507.249565026128, 12362.938185309376, 12990.35048247565, 13698.452061917811, 14423.42306307736, 14429.278536072945], "value": 8933.346231321919, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1514.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 145.0, 134.0, 160.0, 100.0, 163.0, 147.0, 167.0, 119.0], "value": 135.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 108.0, 139.0, 140.0, 106.0, 129.0, 110.0, 139.0, 139.0, 112.0], "value": 125.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 201.0, 203.0, 202.0, 195.0, 195.0, 198.0, 199.0, 202.0, 195.0], "value": 198.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 78.0, 81.0, 153.0, 61.0, 57.0, 73.0, 82.0, 162.0, 58.0], "value": 87.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 122.0, 69.0, 69.0, 72.0, 107.0, 71.0, 69.0, 113.0], "value": 82.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 90.0, 90.0, 90.0, 91.0, 89.0, 89.0, 91.0, 94.0], "value": 90.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 98.0, 97.0, 100.0, 98.0, 97.0, 105.0, 96.0, 98.0], "value": 98.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 137.0, 89.0, 93.0, 92.0, 92.0, 95.0, 90.0, 89.0, 94.0], "value": 98.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 48.0, 48.0, 49.0, 52.0, 53.0, 53.0, 53.0, 51.0, 51.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 55.0, 55.0, 63.0, 61.0, 57.0, 56.0, 55.0, 60.0], "value": 57.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 79.0, 82.0, 78.0, 78.0, 79.0, 80.0, 81.0, 78.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 145.0, 134.0, 122.0, 153.0, 151.0, 164.0, 151.0, 141.0, 175.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 189.0, 155.0, 217.0, 226.0, 186.0, 186.0, 218.0, 209.0, 177.0], "value": 194.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 64.0, 63.0, 67.0, 66.0, 81.0, 92.0, 56.0, 73.0], "value": 69.0, "unit": "score"}]}]} 10:28:51 INFO - Return code: 0 10:28:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:28:51 INFO - # TBPL SUCCESS # 10:28:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:28:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:28:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:28:51 INFO - 2017-06-13 10:28:51.966 screenresolution[2030:12264] starting screenresolution argv=screenresolution get 10:28:51 INFO - 2017-06-13 10:28:51.968 screenresolution[2030:12264] Display 0: 1600x1200x32@60 10:28:51 INFO - 2017-06-13 10:28:51.974 screenresolution[2031:12267] starting screenresolution argv=screenresolution list 10:28:51 INFO - Available Modes on Display 0 10:28:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:28:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:28:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:28:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:28:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:28:52 INFO - Intel Iris: 10:28:52 INFO - Chipset Model: Intel Iris 10:28:52 INFO - Type: GPU 10:28:52 INFO - Bus: Built-In 10:28:52 INFO - VRAM (Dynamic, Max): 1536 MB 10:28:52 INFO - Vendor: Intel (0x8086) 10:28:52 INFO - Device ID: 0x0a2e 10:28:52 INFO - Revision ID: 0x0009 10:28:52 INFO - Displays: 10:28:52 INFO - Display: 10:28:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:28:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:28:52 INFO - Display Serial Number: 1600x1200 60 10:28:52 INFO - Main Display: Yes 10:28:52 INFO - Mirror: Off 10:28:52 INFO - Online: Yes 10:28:52 INFO - Rotation: Supported 10:28:52 INFO - Return code: 0 10:28:52 INFO - Running post-action listener: _resource_record_post_action 10:28:52 INFO - [mozharness: 2017-06-13 17:28:52.213276Z] Finished run-tests step (success) 10:28:52 INFO - Running post-run listener: _resource_record_post_run 10:28:52 INFO - Total resource usage - Wall time: 1254s; CPU: 53.0%; Read bytes: 35938816; Write bytes: 561539072; Read time: 334; Write time: 1754 10:28:52 INFO - TinderboxPrint: CPU usage
53.1% 10:28:52 INFO - TinderboxPrint: I/O read bytes / time
35,938,816 / 334 10:28:52 INFO - TinderboxPrint: I/O write bytes / time
561,539,072 / 1,754 10:28:52 INFO - TinderboxPrint: CPU idle
2,354.2 (46.9%) 10:28:52 INFO - TinderboxPrint: CPU system
1,014.4 (20.2%) 10:28:52 INFO - TinderboxPrint: CPU user
1,649.1 (32.9%) 10:28:52 INFO - TinderboxPrint: Swap in / out
275,574,784 / 0 10:28:52 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 244079104; Write bytes: 243818496; Read time: 18201; Write time: 428 10:28:52 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:28:52 INFO - run-tests - Wall time: 1233s; CPU: 53.0%; Read bytes: 31130112; Write bytes: 316319744; Read time: 297; Write time: 1322 10:28:52 INFO - Running post-run listener: _upload_blobber_files 10:28:52 INFO - Blob upload gear active. 10:28:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:28:52 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 10:28:52 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'] 10:28:52 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 10:28:53 INFO - (blobuploader) - INFO - Open directory for files ... 10:28:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:28:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:28:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:28:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:28:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:28:54 INFO - (blobuploader) - INFO - Done attempting. 10:28:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:28:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:28:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:28:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:28:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:28:55 INFO - (blobuploader) - INFO - Done attempting. 10:28:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:28:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:28:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:28:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:28:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:28:56 INFO - (blobuploader) - INFO - Done attempting. 10:28:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:28:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:28:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:28:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:28:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:28:58 INFO - (blobuploader) - INFO - Done attempting. 10:28:58 INFO - (blobuploader) - INFO - Iteration through files over. 10:28:58 INFO - Return code: 0 10:28:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:28:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:28:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7211bb75c5deac5afccd01d5992759e93965f865b93b29cfcf81b50133b8b7cde1c48ef62879aec3aec9e5d98d5321f2413a3e95c31d3129744bb42ec9e88f8c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6154ade52e668ed224abdea14c736f8800d4ca58874f8a0addd0c0abdd746af57da06abfa23a84df8a25165a13b3c263b58dacc4e259b2b53ae161c246bb9e2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/876a4ca6b76dd0566e38ee3555bb8147198fe3dd00141a43b5598459e7f001f84d476ead915fa40e37ccd6dcf8554058f54f01490f431ea63f45fa7a867aac38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ab2b160482128ba08e593060f7fdd4e69aa5386fb52a250b1b59e32d2b7f2895d7fa541edc180dc08ae8a081df5d96231837ee06ee9f63ebe275df6043e7fb1a"} 10:28:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:28:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:28:58 INFO - Contents: 10:28:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7211bb75c5deac5afccd01d5992759e93965f865b93b29cfcf81b50133b8b7cde1c48ef62879aec3aec9e5d98d5321f2413a3e95c31d3129744bb42ec9e88f8c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6154ade52e668ed224abdea14c736f8800d4ca58874f8a0addd0c0abdd746af57da06abfa23a84df8a25165a13b3c263b58dacc4e259b2b53ae161c246bb9e2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/876a4ca6b76dd0566e38ee3555bb8147198fe3dd00141a43b5598459e7f001f84d476ead915fa40e37ccd6dcf8554058f54f01490f431ea63f45fa7a867aac38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ab2b160482128ba08e593060f7fdd4e69aa5386fb52a250b1b59e32d2b7f2895d7fa541edc180dc08ae8a081df5d96231837ee06ee9f63ebe275df6043e7fb1a"} 10:28:58 INFO - Running post-run listener: copy_logs_to_upload_dir 10:28:58 INFO - Copying logs to upload dir... 10:28:58 INFO - mkdir: /builds/slave/test/build/upload/logs 10:28:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.281756 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-13 10:28:58.570903) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 10:28:58.574210) ========= 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.8Ethf33D2z/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.FrRfVDOE8T/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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/7211bb75c5deac5afccd01d5992759e93965f865b93b29cfcf81b50133b8b7cde1c48ef62879aec3aec9e5d98d5321f2413a3e95c31d3129744bb42ec9e88f8c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6154ade52e668ed224abdea14c736f8800d4ca58874f8a0addd0c0abdd746af57da06abfa23a84df8a25165a13b3c263b58dacc4e259b2b53ae161c246bb9e2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/876a4ca6b76dd0566e38ee3555bb8147198fe3dd00141a43b5598459e7f001f84d476ead915fa40e37ccd6dcf8554058f54f01490f431ea63f45fa7a867aac38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ab2b160482128ba08e593060f7fdd4e69aa5386fb52a250b1b59e32d2b7f2895d7fa541edc180dc08ae8a081df5d96231837ee06ee9f63ebe275df6043e7fb1a"} build_url:https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011385 build_url: 'https://queue.taskcluster.net/v1/task/RUC_YpMMRnCBL66JlSFDUg/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/7211bb75c5deac5afccd01d5992759e93965f865b93b29cfcf81b50133b8b7cde1c48ef62879aec3aec9e5d98d5321f2413a3e95c31d3129744bb42ec9e88f8c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6154ade52e668ed224abdea14c736f8800d4ca58874f8a0addd0c0abdd746af57da06abfa23a84df8a25165a13b3c263b58dacc4e259b2b53ae161c246bb9e2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/876a4ca6b76dd0566e38ee3555bb8147198fe3dd00141a43b5598459e7f001f84d476ead915fa40e37ccd6dcf8554058f54f01490f431ea63f45fa7a867aac38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ab2b160482128ba08e593060f7fdd4e69aa5386fb52a250b1b59e32d2b7f2895d7fa541edc180dc08ae8a081df5d96231837ee06ee9f63ebe275df6043e7fb1a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 10:28:58.608533) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:28:58.608899) ========= 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.8Ethf33D2z/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.FrRfVDOE8T/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.005449 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:28:58.658373) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 10:28:58.658803) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 10:28:58.659293) ========= ========= Total master_lag: 0.20 =========