builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0002 starttime: 1498072075.05 results: success (0) buildid: 20170621111549 builduid: 2ad414d771ef407f8852b9add51654b4 revision: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:55.053088) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:55.054002) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:55.054665) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:55.080064) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:55.080385) ========= 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.Bczb4Fvcuo/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.Rc8EiEi9PS/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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-21 12:07:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.72M=0.002s 2017-06-21 12:07:55 (6.72 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126393 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:55.229549) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:55.229882) ========= 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.Bczb4Fvcuo/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.Rc8EiEi9PS/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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.022362 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:55.279091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-21 12:07:55.279891) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Bczb4Fvcuo/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.Rc8EiEi9PS/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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-21 12:07:55,357 truncating revision to first 12 chars 2017-06-21 12:07:55,357 Setting DEBUG logging. 2017-06-21 12:07:55,357 attempt 1/10 2017-06-21 12:07:55,357 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/4f227bec7585?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-21 12:07:56,480 unpacking tar archive at: pine-4f227bec7585/testing/mozharness/ program finished with exit code 0 elapsedTime=1.384557 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-21 12:07:56.689804) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:56.690332) ========= script_repo_revision: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:56.690953) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:56.691401) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:56.709625) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-06-21 12:07:56.710007) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Bczb4Fvcuo/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.Rc8EiEi9PS/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:07:56 INFO - MultiFileLogger online at 20170621 12:07:56 in /builds/slave/test 12:07:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 12:07:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:07:56 INFO - {'append_to_log': False, 12:07:56 INFO - 'base_work_dir': '/builds/slave/test', 12:07:56 INFO - 'blob_upload_branch': 'Pine', 12:07:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:07:56 INFO - 'branch': 'Pine', 12:07:56 INFO - 'buildbot_json_path': 'buildprops.json', 12:07:56 INFO - 'config_files': ('talos/mac_config.py',), 12:07:56 INFO - 'default_actions': ('clobber', 12:07:56 INFO - 'read-buildbot-config', 12:07:56 INFO - 'download-and-extract', 12:07:56 INFO - 'populate-webroot', 12:07:56 INFO - 'create-virtualenv', 12:07:56 INFO - 'install', 12:07:56 INFO - 'setup-mitmproxy', 12:07:56 INFO - 'run-tests'), 12:07:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:07:56 INFO - 'download_minidump_stackwalk': True, 12:07:56 INFO - 'download_symbols': 'ondemand', 12:07:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:07:56 INFO - 'tooltool.py': '/tools/tooltool.py', 12:07:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:07:56 INFO - '/tools/misc-python/virtualenv.py')}, 12:07:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:07:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:07:56 INFO - 'gecko_profile': False, 12:07:56 INFO - 'gecko_profile_interval': 0, 12:07:56 INFO - 'installer_path': 'installer.exe', 12:07:56 INFO - 'log_level': 'info', 12:07:56 INFO - 'log_name': 'talos', 12:07:56 INFO - 'log_to_console': True, 12:07:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:07:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:07:56 INFO - 'opt_config_files': (), 12:07:56 INFO - 'pip_index': False, 12:07:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:07:56 INFO - 'cmd': ('bash', 12:07:56 INFO - '-c', 12:07:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:07:56 INFO - 'enabled': True, 12:07:56 INFO - 'halt_on_failure': False, 12:07:56 INFO - 'name': 'check_screen_resolution'},), 12:07:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:07:56 INFO - 'cmd': ('bash', 12:07:56 INFO - '-c', 12:07:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:07:56 INFO - 'enabled': True, 12:07:56 INFO - 'halt_on_failure': False, 12:07:56 INFO - 'name': 'check_screen_resolution'},), 12:07:56 INFO - 'run_cmd_checks_enabled': True, 12:07:56 INFO - 'suite': 'dromaeojs-e10s', 12:07:56 INFO - 'system_bits': '32', 12:07:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:07:56 INFO - 'title': 't-yosemite-r7-0002', 12:07:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:07:56 INFO - 'use_talos_json': True, 12:07:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:07:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:07:56 INFO - 'work_dir': 'build'} 12:07:56 INFO - [mozharness: 2017-06-21 19:07:56.909133Z] Running clobber step. 12:07:56 INFO - Running pre-action listener: _resource_record_pre_action 12:07:56 INFO - Running main action method: clobber 12:07:56 INFO - rmtree: /builds/slave/test/build 12:07:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:07:59 INFO - Running post-action listener: _resource_record_post_action 12:07:59 INFO - [mozharness: 2017-06-21 19:07:59.135020Z] Finished clobber step (success) 12:07:59 INFO - [mozharness: 2017-06-21 19:07:59.135142Z] Running read-buildbot-config step. 12:07:59 INFO - Running pre-action listener: _resource_record_pre_action 12:07:59 INFO - Running main action method: read_buildbot_config 12:07:59 INFO - Using buildbot properties: 12:07:59 INFO - { 12:07:59 INFO - "project": "", 12:07:59 INFO - "product": "firefox", 12:07:59 INFO - "script_repo_revision": "default", 12:07:59 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 12:07:59 INFO - "repository": "", 12:07:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 12:07:59 INFO - "buildid": "20170621111549", 12:07:59 INFO - "stage_platform": "macosx64", 12:07:59 INFO - "basedir": "/builds/slave/test", 12:07:59 INFO - "buildnumber": 2, 12:07:59 INFO - "platform": "yosemite_r7", 12:07:59 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:07:59 INFO - "slavebuilddir": "test", 12:07:59 INFO - "branch": "pine", 12:07:59 INFO - "revision": "4f227bec7585a7e4487d7da44c5cc7edd2ad5654", 12:07:59 INFO - "slavename": "t-yosemite-r7-0002", 12:07:59 INFO - "builduid": "2ad414d771ef407f8852b9add51654b4", 12:07:59 INFO - "repo_path": "projects/pine" 12:07:59 INFO - } 12:07:59 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg. 12:07:59 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.test_packages.json. 12:07:59 INFO - Running post-action listener: _resource_record_post_action 12:07:59 INFO - [mozharness: 2017-06-21 19:07:59.139039Z] Finished read-buildbot-config step (success) 12:07:59 INFO - [mozharness: 2017-06-21 19:07:59.139168Z] Running download-and-extract step. 12:07:59 INFO - Running pre-action listener: _resource_record_pre_action 12:07:59 INFO - Running main action method: download_and_extract 12:07:59 INFO - mkdir: /builds/slave/test/build/tests 12:07:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:59 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.test_packages.json 12:07:59 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 12:07:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json'}, attempt #1 12:07:59 INFO - Downloaded 1328 bytes. 12:07:59 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 12:07:59 INFO - Using the following test package requirements: 12:07:59 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:59 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 12:07:59 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 12:07:59 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:59 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 12:07:59 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:59 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 12:07:59 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:59 INFO - u'jsshell-mac.zip'], 12:07:59 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:59 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 12:07:59 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 12:07:59 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:59 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 12:07:59 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:59 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 12:07:59 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:59 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 12:07:59 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:59 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 12:07:59 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:07:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip 12:07:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:07:59 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip into memory 12:08:00 INFO - Content-Length response header: 59596040 12:08:00 INFO - Bytes received: 59596040 12:08:18 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip', u'firefox-56.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:08:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip 12:08:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:08:18 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip into memory 12:08:20 INFO - Content-Length response header: 59596040 12:08:20 INFO - Bytes received: 59596040 12:08:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.talos.tests.zip 12:08:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:08:37 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 12:08:38 INFO - Content-Length response header: 14457085 12:08:38 INFO - Bytes received: 14457085 12:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:38 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg 12:08:38 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 12:08:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg'}, attempt #1 12:08:40 INFO - Downloaded 69703961 bytes. 12:08:40 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg 12:08:40 INFO - mkdir: /builds/slave/test/properties 12:08:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:08:40 INFO - Writing to file /builds/slave/test/properties/build_url 12:08:40 INFO - Contents: 12:08:40 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg 12:08:41 INFO - Running post-action listener: _resource_record_post_action 12:08:41 INFO - Running post-action listener: set_extra_try_arguments 12:08:41 INFO - [mozharness: 2017-06-21 19:08:41.181433Z] Finished download-and-extract step (success) 12:08:41 INFO - [mozharness: 2017-06-21 19:08:41.181555Z] Running populate-webroot step. 12:08:41 INFO - Running pre-action listener: _resource_record_pre_action 12:08:41 INFO - Running main action method: populate_webroot 12:08:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:08:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:08:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:08:41 INFO - '--tppagecycles', 12:08:41 INFO - '7'], 12:08:41 INFO - 'tests': ['tsvgm']}}, 12:08:41 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:08:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:08:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:08:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:08:41 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:08:41 INFO - 'talos_options': ['--disable-e10s'], 12:08:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:08:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:41 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:08:41 INFO - 'talos_options': ['--disable-e10s'], 12:08:41 INFO - 'tests': ['damp', 'tps']}, 12:08:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:08:41 INFO - 'tests': ['damp', 'tps']}, 12:08:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:08:41 INFO - 'tests': ['dromaeo_dom']}, 12:08:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:08:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:08:41 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:08:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:08:41 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:08:41 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:08:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:08:41 INFO - 'tests': ['a11yr', 12:08:41 INFO - 'ts_paint', 12:08:41 INFO - 'tpaint', 12:08:41 INFO - 'sessionrestore', 12:08:41 INFO - 'sessionrestore_no_auto_restore', 12:08:41 INFO - 'tabpaint']}, 12:08:41 INFO - 'other-e10s': {'tests': ['a11yr', 12:08:41 INFO - 'ts_paint', 12:08:41 INFO - 'tpaint', 12:08:41 INFO - 'sessionrestore', 12:08:41 INFO - 'sessionrestore_no_auto_restore', 12:08:41 INFO - 'tabpaint']}, 12:08:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:08:41 INFO - 'ts_paint', 12:08:41 INFO - 'tpaint', 12:08:41 INFO - 'sessionrestore', 12:08:41 INFO - 'sessionrestore_no_auto_restore', 12:08:41 INFO - 'tabpaint']}, 12:08:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:08:41 INFO - 'ts_paint', 12:08:41 INFO - 'tpaint', 12:08:41 INFO - 'sessionrestore', 12:08:41 INFO - 'sessionrestore_no_auto_restore', 12:08:41 INFO - 'tabpaint']}, 12:08:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:08:41 INFO - 'tests': ['a11yr', 12:08:41 INFO - 'ts_paint', 12:08:41 INFO - 'tpaint', 12:08:41 INFO - 'sessionrestore', 12:08:41 INFO - 'sessionrestore_no_auto_restore', 12:08:41 INFO - 'tabpaint']}, 12:08:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:08:41 INFO - 'tests': ['a11yr', 12:08:41 INFO - 'ts_paint', 12:08:41 INFO - 'tpaint', 12:08:41 INFO - 'sessionrestore', 12:08:41 INFO - 'sessionrestore_no_auto_restore', 12:08:41 INFO - 'tabpaint']}, 12:08:41 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:08:41 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:08:41 INFO - 'bloom_basic_ref']}, 12:08:41 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:08:41 INFO - 'talos_options': ['--mitmproxy', 12:08:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:08:41 INFO - '--firstNonBlankPaint'], 12:08:41 INFO - 'tests': ['quantum_pageload_google', 12:08:41 INFO - 'quantum_pageload_youtube', 12:08:41 INFO - 'quantum_pageload_amazon', 12:08:41 INFO - 'quantum_pageload_facebook']}, 12:08:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:08:41 INFO - 'tests': ['tsvgx', 12:08:41 INFO - 'tsvgr_opacity', 12:08:41 INFO - 'tart', 12:08:41 INFO - 'tscrollx', 12:08:41 INFO - 'cart', 12:08:41 INFO - 'tsvg_static']}, 12:08:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:08:41 INFO - 'tsvgr_opacity', 12:08:41 INFO - 'tart', 12:08:41 INFO - 'tscrollx', 12:08:41 INFO - 'cart', 12:08:41 INFO - 'tsvg_static']}, 12:08:41 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:08:41 INFO - 'talos_options': ['--disable-e10s'], 12:08:41 INFO - 'tests': ['tp5o']}, 12:08:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:08:41 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:08:41 INFO - 'talos_options': ['--disable-e10s', 12:08:41 INFO - '--xperf_path', 12:08:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:41 INFO - 'tests': ['tp5n']}, 12:08:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:08:41 INFO - 'talos_options': ['--xperf_path', 12:08:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:41 INFO - 'tests': ['tp5n']}}, 12:08:41 INFO - 'talos.zip': {'path': '', 12:08:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:08:41 INFO - Running post-action listener: _resource_record_post_action 12:08:41 INFO - [mozharness: 2017-06-21 19:08:41.202420Z] Finished populate-webroot step (success) 12:08:41 INFO - [mozharness: 2017-06-21 19:08:41.202534Z] Running create-virtualenv step. 12:08:41 INFO - Running pre-action listener: _resource_record_pre_action 12:08:41 INFO - Running main action method: create_virtualenv 12:08:41 INFO - Creating virtualenv /builds/slave/test/build/venv 12:08:41 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:08:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:08:41 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:08:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:08:41 INFO - Using real prefix '/tools/python27' 12:08:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:08:42 INFO - Installing distribute.............................................................................................................................................................................................done. 12:08:44 INFO - Installing pip.................done. 12:08:44 INFO - Return code: 0 12:08:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:08:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:08:45 INFO - Reading from file tmpfile_stdout 12:08:45 INFO - Output received: 12:08:45 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:08:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:08: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')]} 12:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08: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')]} 12:08:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:45 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 0x105f94c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae004db6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cead30>, '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 0x105cb4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cdbbe8>, '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.Bczb4Fvcuo/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.Rc8EiEi9PS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:08:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:08:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bczb4Fvcuo/Render', 12:08:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:45 INFO - 'HOME': '/Users/cltbld', 12:08:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:45 INFO - 'LOGNAME': 'cltbld', 12:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:45 INFO - 'MOZ_NO_REMOTE': '1', 12:08:45 INFO - 'NO_EM_RESTART': '1', 12:08:45 INFO - 'PAGER': '/bin/cat', 12:08:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:45 INFO - 'PWD': '/builds/slave/test', 12:08:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:45 INFO - 'SHELL': '/bin/bash', 12:08:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rc8EiEi9PS/Listeners', 12:08:45 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 12:08:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:45 INFO - 'USER': 'cltbld', 12:08:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:45 INFO - 'XPC_FLAGS': '0x0', 12:08:45 INFO - 'XPC_SERVICE_NAME': '0', 12:08:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:08:45 INFO - Cleaning up... 12:08:45 INFO - Return code: 0 12:08:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:08: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')]} 12:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08: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')]} 12:08:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:45 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 0x105f94c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae004db6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cead30>, '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 0x105cb4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cdbbe8>, '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.Bczb4Fvcuo/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.Rc8EiEi9PS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:08:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:08:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bczb4Fvcuo/Render', 12:08:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:45 INFO - 'HOME': '/Users/cltbld', 12:08:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:45 INFO - 'LOGNAME': 'cltbld', 12:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:45 INFO - 'MOZ_NO_REMOTE': '1', 12:08:45 INFO - 'NO_EM_RESTART': '1', 12:08:45 INFO - 'PAGER': '/bin/cat', 12:08:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:45 INFO - 'PWD': '/builds/slave/test', 12:08:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:45 INFO - 'SHELL': '/bin/bash', 12:08:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rc8EiEi9PS/Listeners', 12:08:45 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 12:08:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:45 INFO - 'USER': 'cltbld', 12:08:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:45 INFO - 'XPC_FLAGS': '0x0', 12:08:45 INFO - 'XPC_SERVICE_NAME': '0', 12:08:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:45 INFO - Downloading/unpacking psutil>=3.1.1 12:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:08:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:08:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:08:47 INFO - Installing collected packages: psutil 12:08:47 INFO - Running setup.py install for psutil 12:08:47 INFO - building 'psutil._psutil_osx' extension 12:08:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:08:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:08:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:08:48 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:08:48 INFO - building 'psutil._psutil_posix' extension 12:08:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:08:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:08:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:08:48 INFO - ^ 12:08:48 INFO - 1 warning generated. 12:08:48 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:08:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:08:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:08:48 INFO - Successfully installed psutil 12:08:48 INFO - Cleaning up... 12:08:48 INFO - Return code: 0 12:08:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:48 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 0x105f94c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae004db6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cead30>, '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 0x105cb4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cdbbe8>, '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.Bczb4Fvcuo/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.Rc8EiEi9PS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:08:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bczb4Fvcuo/Render', 12:08:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:48 INFO - 'HOME': '/Users/cltbld', 12:08:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:48 INFO - 'LOGNAME': 'cltbld', 12:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:48 INFO - 'MOZ_NO_REMOTE': '1', 12:08:48 INFO - 'NO_EM_RESTART': '1', 12:08:48 INFO - 'PAGER': '/bin/cat', 12:08:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:48 INFO - 'PWD': '/builds/slave/test', 12:08:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:48 INFO - 'SHELL': '/bin/bash', 12:08:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rc8EiEi9PS/Listeners', 12:08:48 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 12:08:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:48 INFO - 'USER': 'cltbld', 12:08:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:48 INFO - 'XPC_FLAGS': '0x0', 12:08:48 INFO - 'XPC_SERVICE_NAME': '0', 12:08:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:08:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:08:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:08:50 INFO - Installing collected packages: mozsystemmonitor 12:08:50 INFO - Running setup.py install for mozsystemmonitor 12:08:50 INFO - Successfully installed mozsystemmonitor 12:08:50 INFO - Cleaning up... 12:08:50 INFO - Return code: 0 12:08:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:08: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')]} 12:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08: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')]} 12:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:50 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 0x105f94c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae004db6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cead30>, '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 0x105cb4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cdbbe8>, '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.Bczb4Fvcuo/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.Rc8EiEi9PS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:08:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bczb4Fvcuo/Render', 12:08:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:50 INFO - 'HOME': '/Users/cltbld', 12:08:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:50 INFO - 'LOGNAME': 'cltbld', 12:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:50 INFO - 'MOZ_NO_REMOTE': '1', 12:08:50 INFO - 'NO_EM_RESTART': '1', 12:08:50 INFO - 'PAGER': '/bin/cat', 12:08:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:50 INFO - 'PWD': '/builds/slave/test', 12:08:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:50 INFO - 'SHELL': '/bin/bash', 12:08:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rc8EiEi9PS/Listeners', 12:08:50 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 12:08:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:50 INFO - 'USER': 'cltbld', 12:08:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:50 INFO - 'XPC_FLAGS': '0x0', 12:08:50 INFO - 'XPC_SERVICE_NAME': '0', 12:08:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:50 INFO - Downloading/unpacking jsonschema==2.5.1 12:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:08:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:52 INFO - Downloading functools32-3.2.3-2.tar.gz 12:08:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:08:52 INFO - warning: no files found matching '*.txt' 12:08:52 INFO - no previously-included directories found matching 'build' 12:08:52 INFO - no previously-included directories found matching 'dist' 12:08:52 INFO - no previously-included directories found matching '.git*' 12:08:52 INFO - Installing collected packages: jsonschema, functools32 12:08:52 INFO - Running setup.py install for functools32 12:08:52 INFO - warning: no files found matching '*.txt' 12:08:52 INFO - no previously-included directories found matching 'build' 12:08:52 INFO - no previously-included directories found matching 'dist' 12:08:52 INFO - no previously-included directories found matching '.git*' 12:08:52 INFO - Successfully installed jsonschema functools32 12:08:52 INFO - Cleaning up... 12:08:52 INFO - Return code: 0 12:08:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:08:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:53 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 0x105f94c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae004db6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cead30>, '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 0x105cb4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cdbbe8>, '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.Bczb4Fvcuo/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.Rc8EiEi9PS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:08:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:08:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bczb4Fvcuo/Render', 12:08:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:53 INFO - 'HOME': '/Users/cltbld', 12:08:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:53 INFO - 'LOGNAME': 'cltbld', 12:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:53 INFO - 'MOZ_NO_REMOTE': '1', 12:08:53 INFO - 'NO_EM_RESTART': '1', 12:08:53 INFO - 'PAGER': '/bin/cat', 12:08:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:53 INFO - 'PWD': '/builds/slave/test', 12:08:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:53 INFO - 'SHELL': '/bin/bash', 12:08:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rc8EiEi9PS/Listeners', 12:08:53 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 12:08:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:53 INFO - 'USER': 'cltbld', 12:08:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:53 INFO - 'XPC_FLAGS': '0x0', 12:08:53 INFO - 'XPC_SERVICE_NAME': '0', 12:08:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:08:53 INFO - Cleaning up... 12:08:53 INFO - Return code: 0 12:08:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:53 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 0x105f94c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae004db6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cead30>, '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 0x105cb4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cdbbe8>, '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.Bczb4Fvcuo/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.Rc8EiEi9PS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:08:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:08:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bczb4Fvcuo/Render', 12:08:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:53 INFO - 'HOME': '/Users/cltbld', 12:08:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:53 INFO - 'LOGNAME': 'cltbld', 12:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:53 INFO - 'MOZ_NO_REMOTE': '1', 12:08:53 INFO - 'NO_EM_RESTART': '1', 12:08:53 INFO - 'PAGER': '/bin/cat', 12:08:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:53 INFO - 'PWD': '/builds/slave/test', 12:08:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:53 INFO - 'SHELL': '/bin/bash', 12:08:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rc8EiEi9PS/Listeners', 12:08:53 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 12:08:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:53 INFO - 'USER': 'cltbld', 12:08:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:53 INFO - 'XPC_FLAGS': '0x0', 12:08:53 INFO - 'XPC_SERVICE_NAME': '0', 12:08:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:54 INFO - Downloading/unpacking blobuploader==1.2.4 12:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:55 INFO - Downloading blobuploader-1.2.4.tar.gz 12:08:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:08:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:08:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:56 INFO - Downloading docopt-0.6.1.tar.gz 12:08:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:08:56 INFO - Installing collected packages: blobuploader, requests, docopt 12:08:56 INFO - Running setup.py install for blobuploader 12:08:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:08:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:08:57 INFO - Running setup.py install for requests 12:08:57 INFO - Running setup.py install for docopt 12:08:57 INFO - Successfully installed blobuploader requests docopt 12:08:57 INFO - Cleaning up... 12:08:57 INFO - Return code: 0 12:08:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:57 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 0x105f94c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae004db6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cead30>, '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 0x105cb4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cdbbe8>, '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.Bczb4Fvcuo/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.Rc8EiEi9PS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:08:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bczb4Fvcuo/Render', 12:08:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:57 INFO - 'HOME': '/Users/cltbld', 12:08:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:57 INFO - 'LOGNAME': 'cltbld', 12:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:57 INFO - 'MOZ_NO_REMOTE': '1', 12:08:57 INFO - 'NO_EM_RESTART': '1', 12:08:57 INFO - 'PAGER': '/bin/cat', 12:08:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:57 INFO - 'PWD': '/builds/slave/test', 12:08:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:57 INFO - 'SHELL': '/bin/bash', 12:08:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rc8EiEi9PS/Listeners', 12:08:57 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 12:08:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:57 INFO - 'USER': 'cltbld', 12:08:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:57 INFO - 'XPC_FLAGS': '0x0', 12:08:57 INFO - 'XPC_SERVICE_NAME': '0', 12:08:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:58 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-18TnhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:58 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-CyUn2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:58 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-U5V_1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:58 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-vDJFxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:08:58 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-aJnUih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:08:58 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-BmNAI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:08:58 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Ljx5eG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:08:58 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Hpf8hX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:08:58 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-imz7dZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:08:58 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-y_f9DE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:08:59 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-HF3X_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:08:59 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-BcNiwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:08:59 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-vEKTYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:08:59 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Mt3nyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:59 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-UB3MFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:08:59 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-oeu2a7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:08:59 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-B_FSca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:08:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:08:59 INFO - Running setup.py install for manifestparser 12:08:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:08:59 INFO - Running setup.py install for mozcrash 12:09:00 INFO - Running setup.py install for mozdebug 12:09:00 INFO - Running setup.py install for mozdevice 12:09:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:09:00 INFO - Running setup.py install for mozfile 12:09:00 INFO - Running setup.py install for mozhttpd 12:09:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:09:00 INFO - Running setup.py install for mozinfo 12:09:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:09:00 INFO - Running setup.py install for mozInstall 12:09:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:09:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:09:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:09:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:09:00 INFO - Running setup.py install for mozleak 12:09:01 INFO - Running setup.py install for mozlog 12:09:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:09:01 INFO - Running setup.py install for moznetwork 12:09:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:09:01 INFO - Running setup.py install for mozprocess 12:09:01 INFO - Running setup.py install for mozprofile 12:09:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:09:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:09:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:09:01 INFO - Running setup.py install for mozrunner 12:09:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:09:01 INFO - Running setup.py install for mozscreenshot 12:09:02 INFO - Running setup.py install for moztest 12:09:02 INFO - Running setup.py install for mozversion 12:09:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:09:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:09:02 INFO - Cleaning up... 12:09:02 INFO - Return code: 0 12:09:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:02 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 0x105f94c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae004db6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cead30>, '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 0x105cb4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cdbbe8>, '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.Bczb4Fvcuo/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.Rc8EiEi9PS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:09:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bczb4Fvcuo/Render', 12:09:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:02 INFO - 'HOME': '/Users/cltbld', 12:09:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:02 INFO - 'LOGNAME': 'cltbld', 12:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:02 INFO - 'MOZ_NO_REMOTE': '1', 12:09:02 INFO - 'NO_EM_RESTART': '1', 12:09:02 INFO - 'PAGER': '/bin/cat', 12:09:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:02 INFO - 'PWD': '/builds/slave/test', 12:09:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:02 INFO - 'SHELL': '/bin/bash', 12:09:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rc8EiEi9PS/Listeners', 12:09:02 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 12:09:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:02 INFO - 'USER': 'cltbld', 12:09:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:02 INFO - 'XPC_FLAGS': '0x0', 12:09:02 INFO - 'XPC_SERVICE_NAME': '0', 12:09:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:02 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-2TyHwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:02 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-eX2dd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:02 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-FsodiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:02 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-MAsj9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:03 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-toMrzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:03 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Gd4NP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:03 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-xnd3ep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:03 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-AKrTPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:03 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-uRjr0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:03 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-YRhlUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:03 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-WwdP9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:03 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-r9tJXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:03 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-ykFW0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:04 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-iUdfPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:04 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-npeAJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:04 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-BHnWYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:04 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-C_7GIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:09:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:05 INFO - Downloading blessings-1.6.tar.gz 12:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:09:05 INFO - Installing collected packages: blessings 12:09:05 INFO - Running setup.py install for blessings 12:09:06 INFO - Successfully installed blessings 12:09:06 INFO - Cleaning up... 12:09:06 INFO - Return code: 0 12:09:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:09:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:06 INFO - Reading from file tmpfile_stdout 12:09:06 INFO - Current package versions: 12:09:06 INFO - blessings == 1.6 12:09:06 INFO - blobuploader == 1.2.4 12:09:06 INFO - docopt == 0.6.1 12:09:06 INFO - functools32 == 3.2.3-2 12:09:06 INFO - jsonschema == 2.5.1 12:09:06 INFO - manifestparser == 1.1 12:09:06 INFO - mozInstall == 1.13 12:09:06 INFO - mozcrash == 1.0 12:09:06 INFO - mozdebug == 0.1 12:09:06 INFO - mozdevice == 0.51 12:09:06 INFO - mozfile == 1.2 12:09:06 INFO - mozhttpd == 0.7 12:09:06 INFO - mozinfo == 0.10 12:09:06 INFO - mozleak == 0.1 12:09:06 INFO - mozlog == 3.4 12:09:06 INFO - moznetwork == 0.27 12:09:06 INFO - mozprocess == 0.25 12:09:06 INFO - mozprofile == 0.28 12:09:06 INFO - mozrunner == 6.13 12:09:06 INFO - mozscreenshot == 0.1 12:09:06 INFO - mozsystemmonitor == 0.3 12:09:06 INFO - moztest == 0.8 12:09:06 INFO - mozversion == 1.4 12:09:06 INFO - psutil == 3.1.1 12:09:06 INFO - requests == 1.2.3 12:09:06 INFO - wsgiref == 0.1.2 12:09:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:06 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 0x105f94c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae004db6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cead30>, '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 0x105cb4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cdbbe8>, '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.Bczb4Fvcuo/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.Rc8EiEi9PS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:09:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:09:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bczb4Fvcuo/Render', 12:09:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:06 INFO - 'HOME': '/Users/cltbld', 12:09:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:06 INFO - 'LOGNAME': 'cltbld', 12:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:06 INFO - 'MOZ_NO_REMOTE': '1', 12:09:06 INFO - 'NO_EM_RESTART': '1', 12:09:06 INFO - 'PAGER': '/bin/cat', 12:09:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:06 INFO - 'PWD': '/builds/slave/test', 12:09:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:06 INFO - 'SHELL': '/bin/bash', 12:09:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rc8EiEi9PS/Listeners', 12:09:06 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 12:09:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:06 INFO - 'USER': 'cltbld', 12:09:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:06 INFO - 'XPC_FLAGS': '0x0', 12:09:06 INFO - 'XPC_SERVICE_NAME': '0', 12:09:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:09:06 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:08 INFO - Downloading marionette_driver-1.4.0.tar.gz 12:09:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 12:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:09:08 INFO - Installing collected packages: marionette-driver 12:09:08 INFO - Running setup.py install for marionette-driver 12:09:08 INFO - Successfully installed marionette-driver 12:09:08 INFO - Cleaning up... 12:09:08 INFO - Return code: 0 12:09:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:09:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105f94c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae004db6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cead30>, '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 0x105cb4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cdbbe8>, '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.Bczb4Fvcuo/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.Rc8EiEi9PS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:09:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:09:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bczb4Fvcuo/Render', 12:09:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:08 INFO - 'HOME': '/Users/cltbld', 12:09:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:08 INFO - 'LOGNAME': 'cltbld', 12:09:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:08 INFO - 'MOZ_NO_REMOTE': '1', 12:09:08 INFO - 'NO_EM_RESTART': '1', 12:09:08 INFO - 'PAGER': '/bin/cat', 12:09:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:08 INFO - 'PWD': '/builds/slave/test', 12:09:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:08 INFO - 'SHELL': '/bin/bash', 12:09:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rc8EiEi9PS/Listeners', 12:09:08 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 12:09:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:08 INFO - 'USER': 'cltbld', 12:09:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:08 INFO - 'XPC_FLAGS': '0x0', 12:09:08 INFO - 'XPC_SERVICE_NAME': '0', 12:09:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:09:08 INFO - Cleaning up... 12:09:08 INFO - Return code: 0 12:09:08 INFO - Running post-action listener: _resource_record_post_action 12:09:08 INFO - Running post-action listener: _start_resource_monitoring 12:09:08 INFO - Starting resource monitoring. 12:09:08 INFO - [mozharness: 2017-06-21 19:09:08.737492Z] Finished create-virtualenv step (success) 12:09:08 INFO - [mozharness: 2017-06-21 19:09:08.738509Z] Running install step. 12:09:08 INFO - Running pre-action listener: _resource_record_pre_action 12:09:08 INFO - Running main action method: install 12:09:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:08 INFO - Reading from file tmpfile_stdout 12:09:08 INFO - Detecting whether we're running mozinstall >=1.0... 12:09:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:09:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:09:09 INFO - Reading from file tmpfile_stdout 12:09:09 INFO - Output received: 12:09:09 INFO - Usage: mozinstall [options] installer 12:09:09 INFO - Options: 12:09:09 INFO - -h, --help show this help message and exit 12:09:09 INFO - -d DEST, --destination=DEST 12:09:09 INFO - Directory to install application into. [default: 12:09:09 INFO - "/builds/slave/test"] 12:09:09 INFO - --app=APP Application being installed. [default: firefox] 12:09:09 INFO - mkdir: /builds/slave/test/build/application 12:09:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:09:31 INFO - Reading from file tmpfile_stdout 12:09:31 INFO - Output received: 12:09:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:09:31 INFO - Running post-action listener: _resource_record_post_action 12:09:31 INFO - [mozharness: 2017-06-21 19:09:31.547486Z] Finished install step (success) 12:09:31 INFO - [mozharness: 2017-06-21 19:09:31.547614Z] Running setup-mitmproxy step. 12:09:31 INFO - Running pre-action listener: _resource_record_pre_action 12:09:31 INFO - Running main action method: setup_mitmproxy 12:09:31 INFO - Skipping: mitmproxy is not required 12:09:31 INFO - Running post-action listener: _resource_record_post_action 12:09:31 INFO - [mozharness: 2017-06-21 19:09:31.548233Z] Finished setup-mitmproxy step (success) 12:09:31 INFO - [mozharness: 2017-06-21 19:09:31.548325Z] Running run-tests step. 12:09:31 INFO - Running pre-action listener: _resource_record_pre_action 12:09:31 INFO - Running main action method: run_tests 12:09:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:09:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:09:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:09:31 INFO - 2017-06-21 12:09:31.563 screenresolution[2228:25355] starting screenresolution argv=screenresolution get 12:09:31 INFO - 2017-06-21 12:09:31.565 screenresolution[2228:25355] Display 0: 1600x1200x32@60 12:09:31 INFO - 2017-06-21 12:09:31.571 screenresolution[2229:25357] starting screenresolution argv=screenresolution list 12:09:31 INFO - Available Modes on Display 0 12:09:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:09:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:09:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:09:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:09:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:09:31 INFO - Intel Iris: 12:09:31 INFO - Chipset Model: Intel Iris 12:09:31 INFO - Type: GPU 12:09:31 INFO - Bus: Built-In 12:09:31 INFO - VRAM (Dynamic, Max): 1536 MB 12:09:31 INFO - Vendor: Intel (0x8086) 12:09:31 INFO - Device ID: 0x0a2e 12:09:31 INFO - Revision ID: 0x0009 12:09:31 INFO - Displays: 12:09:31 INFO - Display: 12:09:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:09:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:09:31 INFO - Display Serial Number: 1600x1200 60 12:09:31 INFO - Main Display: Yes 12:09:31 INFO - Mirror: Off 12:09:31 INFO - Online: Yes 12:09:31 INFO - Rotation: Supported 12:09:31 INFO - Return code: 0 12:09:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:09:31 INFO - Python 2.7.3 12:09:31 INFO - Return code: 0 12:09:31 INFO - grabbing minidump binary from tooltool 12:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:31 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 0x105cead30>, '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 0x105cb4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105cdbbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:09:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:09:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:09:31 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:09:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:09:31 INFO - Return code: 0 12:09:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:09:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:09:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:09:31 INFO - ENV: RUST_BACKTRACE is now 1 12:09:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:09:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:09:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0002 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:09:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Bczb4Fvcuo/Render', 12:09:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:31 INFO - 'HOME': '/Users/cltbld', 12:09:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:31 INFO - 'LOGNAME': 'cltbld', 12:09:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:09:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:31 INFO - 'MOZ_NO_REMOTE': '1', 12:09:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:09:31 INFO - 'NO_EM_RESTART': '1', 12:09:31 INFO - 'PAGER': '/bin/cat', 12:09:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:31 INFO - 'PWD': '/builds/slave/test', 12:09:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:09:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:31 INFO - 'RUST_BACKTRACE': '1', 12:09:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:09:31 INFO - 'SHELL': '/bin/bash', 12:09:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Rc8EiEi9PS/Listeners', 12:09:31 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 12:09:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:31 INFO - 'USER': 'cltbld', 12:09:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:31 INFO - 'XPC_FLAGS': '0x0', 12:09:31 INFO - 'XPC_SERVICE_NAME': '0', 12:09:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:09:32 INFO - mozversion application_buildid: 20170620132059 12:09:32 INFO - mozversion application_changeset: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 12:09:32 INFO - mozversion application_display_name: Nightly 12:09:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:09:32 INFO - mozversion application_name: Firefox 12:09:32 INFO - mozversion application_remotingname: firefox 12:09:32 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 12:09:32 INFO - mozversion application_vendor: Mozilla 12:09:32 INFO - mozversion application_version: 56.0a1 12:09:32 INFO - mozversion platform_buildid: 20170620132059 12:09:32 INFO - mozversion platform_changeset: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 12:09:32 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 12:09:32 INFO - mozversion platform_version: 56.0a1 12:09:32 INFO - using testdate: 1498072172 12:09:32 INFO - actual date: 1498072172 12:09:32 INFO - starting webserver on 'localhost:56889' 12:09:32 INFO - SUITE-START | Running 2 tests 12:09:32 INFO - TEST-START | dromaeo_css 12:09:32 INFO - operating with platform_type : mac_ 12:09:32 INFO - Initialising browser for dromaeo_css test... 12:09:32 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56889/getInfo.html -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmp1cT0Wf/profile 12:09:32 INFO - TEST-INFO | started process 2239 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56889/getInfo.html -foreground) 12:09:37 INFO - TEST-INFO | 2239: exit 0 12:09:37 INFO - Browser initialized. 12:09:37 INFO - Running cycle 1/1 for dromaeo_css test... 12:09:37 INFO - TEST-INFO | started process 2245 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmp1cT0Wf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:09:38 INFO - PID 2245 | 2017-06-21 12:09:38.885 plugin-container[2246:25738] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973b, name = 'com.apple.tsm.portname' 12:09:38 INFO - PID 2245 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:09:38 INFO - PID 2245 | 2017-06-21 12:09:38.886 plugin-container[2246:25738] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 12:09:38 INFO - PID 2245 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:09:38 INFO - PID 2245 | 2017-06-21 12:09:38.886 plugin-container[2246:25738] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:09:39 INFO - PID 2245 | 2017-06-21 12:09:39.669 plugin-container[2247:25788] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 12:09:39 INFO - PID 2245 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:09:39 INFO - PID 2245 | 2017-06-21 12:09:39.669 plugin-container[2247:25788] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 12:09:39 INFO - PID 2245 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:09:39 INFO - PID 2245 | 2017-06-21 12:09:39.669 plugin-container[2247:25788] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:09:49 INFO - PID 2245 | RSS: Main: 249942016 12:09:49 INFO - PID 2245 | 12:12:55 INFO - PID 2245 | Cycle 1(1): loaded http://localhost:56889/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56889/tests/dromaeo/cssquery-ext.html) 12:12:57 INFO - PID 2245 | RSS: Main: 270151680 12:12:57 INFO - PID 2245 | 12:15:59 INFO - PID 2245 | Cycle 1(1): loaded http://localhost:56889/tests/dromaeo/cssquery-ext.html (next: http://localhost:56889/tests/dromaeo/cssquery-jquery.html) 12:16:00 INFO - PID 2245 | RSS: Main: 269107200 12:16:00 INFO - PID 2245 | 12:19:01 INFO - PID 2245 | Cycle 1(1): loaded http://localhost:56889/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56889/tests/dromaeo/cssquery-mootools.html) 12:19:02 INFO - PID 2245 | RSS: Main: 271437824 12:19:02 INFO - PID 2245 | 12:22:04 INFO - PID 2245 | Cycle 1(1): loaded http://localhost:56889/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56889/tests/dromaeo/cssquery-prototype.html) 12:22:04 INFO - PID 2245 | RSS: Main: 269733888 12:22:04 INFO - PID 2245 | 12:25:07 INFO - PID 2245 | Cycle 1(1): loaded http://localhost:56889/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56889/tests/dromaeo/cssquery-yui.html) 12:25:08 INFO - PID 2245 | RSS: Main: 273055744 12:25:08 INFO - PID 2245 | 12:28:09 INFO - PID 2245 | Cycle 1(1): loaded http://localhost:56889/tests/dromaeo/cssquery-yui.html (next: http://localhost:56889/tests/dromaeo/cssquery-dojo.html) 12:28:10 INFO - PID 2245 | RSS: Main: 279580672 12:28:10 INFO - PID 2245 | 12:28:10 INFO - PID 2245 | __start_tp_report 12:28:10 INFO - PID 2245 | _x_x_mozilla_page_load 12:28:10 INFO - PID 2245 | _x_x_mozilla_page_load_details 12:28:10 INFO - PID 2245 | |i|pagename|runs| 12:28:10 INFO - PID 2245 | |0;dojo.html;3174.6507884132743;3520.4719292106197;3526.8060256685903;3598.999999999998;3606.7475276730625;6197.535184861136;6238.189035425394;6290.874182516359;6294.937050629493;6323.20959880015;6267.467265282445;6337.683115844201;6343.619382837033;6349.968250158754;6359.68201589921;5546.750396232179;5565;5596.104623260279;5670.574706896979;5683.772649094031;5474.562035037187;5552.639078459907;5584.385717571064;5595.328560572737;5631.521320687726;4725.929111063337;5480.534154596864;5490.3411590609385;5545.140503222007;5561.944380556183;5638.887222255573;5640.633358831663;5641.041023026054;5650;5691.51622112121;5429.619926605139;5543.77824887004;5584.5253153482;5603.3836278009385;5610.130429783349;6162.291336496315;6321.209848768904;6343.682815859234;6365.554411191219;6404.903926441107;5860.648361098348;6749.392554670103;6813.863722725568;6834;6846.144231971004;7001.754938577125;7010.088688470466;7022.332878376546;7043.260457651924;7087.574745515234;5383.676979381171;6422.646754428458;6500.512461565401;6504.414602685781;6505.544611877218;6977.197622273402;7042.260562640951;7049.436045116378;7071.717131314793;7076.681549330293;6513.2184137902805;6531.444827189742;6686.1642294713165;6746.4940129489505;6839.965800170968;7030.613316267552;7055.717771289091;7057.50597458173;7067.57594544329;7074.257202993715;6672.866542669217;6930.3762661360715;6946.7221311148;6995.580265184105;6999.265077166824;6776.559523630948;6788.151481064867;6824.18109826824;6839.760608378683;6862.073620061327;5397.163439666858;5418.756155972991;5419.840469606353;5443.591730620219;5482.451754824486;4857.878553036202;4864.829730959399;4872.756362181876;4882.633802464758;4887.853364399002;5968.850778730566;6422.2293324800385;6496.285408605049;6543.640099794462;6554.016897465323;5849.502792262705;6822.522423430312;7057.258987806309;7066.787996360117;7072.823179420556;6369.172007638977;6390.4248617624635;6403.231612206565;6499.350064993463;6515.283318834924;6021.5182785377065;6118.558266176196;6151.292601350902;6157.107219453154;6162.137300777805;7301.233370496021;7314.780556583311;7316.890246646198;7317.2316906726155;7317.487775855639;7066.222715501188;7072.787816365517;7087.787366379017;7096.325849044332;7097.041899343522;7075.82310442243;7265;7267.890981635385;7289.599072051088;7295.343419092307;5187.455317191791;6777.661116944035;6820.897686534607;6935.167779866449;6936.271691472524;5872.412758724094;6327.493800495858;6987.825304367431;7025.929740702528;7091.893621595577;6804.591724496546;6815.591064536144;6822.692978816064;6827.897581536182;6839.487038472035;6568.1789776277965;6571.671416429255;6670.699818508276;6702.966485167543;6712.2616512184595;6548.770793022221;6670;6722.991551267349;6723.125993620798;6732.091167692299;6868.038474613658;6869.690863911036;6876.587404755731;6878;6880.311968803079;5754.9136762947755;6596.3073877244115;6662.433693135944;6707;6763.695633696397 12:28:10 INFO - PID 2245 | |1;ext.html;912.6714382822183;946.7443790176652;948.4167237149151;965.9806803863919;966.0436168193481;38474.03814904625;39161.80419097904;39259.21481570367;39367.99999999996;39389.606103939026;37966.24067518655;38062;38117.856464306045;38181.61818381815;38217;7756.3794896408435;7898.249666281723;7911.485753426037;7932;7937.44437889348;7806.60966951653;7845;7896.605169741519;7913.28780409763;7943.324817390529;7860.056793184797;7865.646045927947;7896.881546776803;7898.842023159534;7904.881426778603;8505.574721263944;8669.133086691343;8741.562921853882;8755.562221888911;8764.649414023432;7383.261673832628;8417.747467575982;8471.279941205004;8498.150184981514;8505.574721263913;29809.701902981127;35333.64666353355;35642.28715425703;35694.82152589221;35769;3624.818759062063;3643.2713457308646;3683.0055884910926;3691.907702307437;3700.9259814803822;26329.473410531682;30129;30198.84900575505;30252.8487357564;30276.0917172483;3085.922851928697;3161.8419079046184;3181.5227715842807;3192.1381227068796;3208.903732888017;317431;323942.3802880994;324097.9999999976;331964;332519;48365.758171208916;49222;49379.753101234266;49403.752981234866;49448.26931057396;51587.74206128946;51675;51832.22251666228;51897.740511297205;51971;12022.63932082039;14711.970162088764;14729.852701473063;14740.631484212981;14754.483593074405;12001.879981200076;12024.45889934955;12026.518939242507;12044.81932771009;12075.215111017756;13674.453021879212;13704.65738356549;13715.314234288246;13779.931100344635;13786.862131378759;5957.791477298274;5973;5996.790112346047;6045.183900173508;6050.699099693587;999.8900120986685;1003.2425518733455;1003.678822776719;1049.7665243339045;1064.499685147979;19956.600867982557;22573.661395079085;22721.431964201027;22820.65769013466;24190;1079.433297518809;1092.1754075672816;1125.774845030997;1141.50915106505;1148.8851114888444;12320.876791232153;12325.260484370967;12347.444365003596;12350.876491234972;12447.004239660806;340829;341153;345201;345487;345668;51938.74030629823;52171.21743173855;52400;52458.47541524536;52477.475225248025;1416.3130881522454;1488.9851101488846;1490.478332583587;1495.0954672423245;1509.433962264151;21773.84409258237;22127.557448851254;22170;22334.664980024987;22659;37476.25047499089;37703.62296377111;37724.81137594295;37790.811045944596;37821.43267851039;303398;303802;304919;305054;305237;29155.125346239645;29632.259193520335;29738.405231895675;32951.67048329486;33125.67497300178;37477;37650;41117.1776564461;41216.38175427431;42922.35616465693;27296.590551142144;28013;28052.43895122127;28770.712292876804;29182;28408.147755567366;29297.85351073231;29797.255068623457;29848.701512984593;29931;35756.64243357533;35947.28105437824;36105;36415.725449609134;36970.260594788495;23406.531869362178;26262.86868565645;28880.133595992153;30157.69842301549;31977;24305;24619.384515387257;24643.507129857662;24711.50576988486;24833.751662483144 12:28:10 INFO - PID 2245 | |2;jquery.html;570.9551520717088;571.5970240980109;574.1330590807879;575.8597976007508;587.9853003674907;23554;24118.638220426707;24349.513009739796;24389.87805060977;24441;20816.47958801031;23195.76804231957;24995.500269983815;26977;27078.729212707865;6561.343865613437;7069.25772793857;7078.186008609025;7088.964555177217;7092.042574252488;6180.918339290629;6935.341142591471;6957.16514018316;6996.860062798741;7019.964900175492;6342.904856427157;6632.469402447817;6742.460603151761;6760.695768690396;6962.930370696279;6756.831079223034;6843.144606924135;6843.349881761224;6844.349786770249;6849.1780986281465;5557.194206840026;6324.146240257553;6324.2410910691015;6330.0821380899515;6347.492200623985;26285.605715914277;29655.25861853449;30791.84604076965;31555.21111972196;31990.88031918895;2249.505108876061;2283.315005498344;2294.1052989334007;2300.4363930836885;2301.0910690277415;16575.336986520524;19430.93129877856;19731;19819.702704459443;19843.60312793736;1895.3555791030979;2210.7789221077765;2232.4195709115584;2277.3054218463362;2283.0525331987205;128843.35578322236;216425;218150;220627;222113;13357.398917048757;16717.66564668724;16768.490835824836;16999.895006824765;17236.052017138925;22491.88754056219;22518.32445026652;22584;22607.43481412945;22615.434614134778;10166.694999150037;10206.795864082676;10293.433861137559;10308.845367319496;10430.947845260725;9331.860022099674;9336.813263734688;9351.719448416557;9365.719028429157;9392.812143757086;8036.959815200887;9524.666636667684;9630.133288004114;9718.708438746708;9772.560234789451;2023.9392818215476;2331.5803155432186;2656.3624730064644;2815.268030312093;2847.576211894053;957.9233661307079;976.4239098931522;979.4221409368507;998.7079652198078;1012.8578057408675;21991.560168796535;22162.77837221607;22179;22241.666375004388;22975.540489190123;1010.4089107871977;1014.5333146752429;1031.242037102729;1049.266660349793;1056.4453661827602;11105.444727763579;11342.659720208407;11461.656150315504;11464.426778661034;11988.100892433102;604356;604769;614907;616567;617712;160659;163667;164401.53397699044;165123;165916;1151.9673366223508;1172.3141961952183;1199.8500187476566;1209.6612948374293;1210.1589395370308;15630.843691563394;19140.92380111776;19301.806981930564;19477;19764.604707906048;72926;72953.27046729464;73421;73790;75162;94980.57529137206;99310;99519;100354.99645003749;100816;12335.50657973671;12469.251844888973;12528.686782830502;12605.936970315457;12613.558525451564;18747.437576872715;18762.906185468986;18791.154398052393;18856.717149242497;19230.711539326643;14666.926665366605;15217.847821522086;15356.232188390311;15514.5345639631;15550.688986220439;13635.795463067863;13677.726445471233;13777.795394189576;14676.926615366854;14902.403903843724;16426.917865411073;16459.34162633481;16471;16611.169441527632;16614.086225257728;17099.401520946707;17748.467545973643;17798.199081041643;18186.909065454587;18249.452516424528;12192.51229950792;15870.2064896757;15907.28417221251;16129.032258064553;16130.596735081717 12:28:10 INFO - PID 2245 | |3;mootools.html;1423.5373503611327;1539.738244498435;1559.1190977097947;1570.4895908829633;1594.617291849956;14979.700405991875;15576.532704018884;15637.92181039096;15640.765388519181;15653.530394088168;12933;13272.20366777994;13360.665983350422;13386.330683465836;14900.180490073042;2424.9272521824373;2931.2085736851036;2932.794704370695;2935.911922642324;2936.721011503904;2597.298729343076;2620.7903367730537;2621.515019721348;2624.1602687140025;2641.815072944895;2850.3586692994013;2866.455373479043;2890.3352228987346;2890.5086135356933;2900.7099290071037;2539.7968162547136;2554.9233522994246;2558;2562.693026556451;2564.4101856572997;2459.6433517140094;2813.338865366637;2827.7879159063154;2830.938398100712;2844.416894536615;14757;14789.852101478955;14974.47589334379;14991.925040374837;14993.250337483083;7154.213036565973;8113.188681131898;8437.156284371575;8439.367047471464;8457.069722330538;4923.138450771136;5139.948600513984;5184.688918664892;5313.920291195635;5322.121849894763;1508.2986411318789;1513.7199618070692;1518.5140754958418;1535.58539194418;1541.899776514523;22608.321750347517;23969.520609587886;24016.15943441971;24018.879905600534;24057.39856503584;7822.335101516321;7849;7860.253275938777;7893.565853877977;8008.27925486709;18869.245230190914;18873;18902.149403276886;18905.62188756217;18956.620867582573;8387.0354909185;8407.201315874981;8463.407561470638;8481.194286542892;8490.108538603481;6013.909791353133;7430.702771889172;7521.849563008818;7559.1306999695735;7601.6579253933705;5648.63283886546;5843.766249349978;5969.492593129633;6032.034874420072;6070.575059745864;1848.1867978089867;1865.3564520240495;1871.082140805685;1871.6537440573538;1889.9527511812314;788.5978151142958;826.557791581501;861.4443683823898;862.9428949536892;871.4901782457207;7184.137903451619;7304.926950730531;7345.55926644403;7584.2036586158765;7621;692.78177374128;713;714.1644276196862;714.7284032067781;722.5050840174497;4215.515215750167;4429.933550996738;4481.395011673448;5655.095184770417;5793.652380857077;19562.510937226398;19823.504412389804;20166.596668066555;20237;20300.883451410315;19078.523036924187;19781.40655780329;19817.60364792696;19992.60014799725;20212.393628190883;9557.187639050619;9920.107190352903;10192.694219173436;10255.384676919408;10292.353223484206;8168.918310816815;8480.491170529787;8617.784555385915;8639.524826134371;8971;17688.204030818903;18085.547861303574;18205.635887281915;18483.445496635122;18491;17370.73943890868;18477;18584.907075464536;18757.34349297768;18799;10046.447445390573;12572.748545029232;12612.747745044864;12787.040971926957;13272.601821945356;13317.600471985856;13480.662983425493;13793.758276904706;13864.89080873548;13993.370298336802;8492.660293588187;10476.63331783384;10541.472926353805;10959.56161753521;11074.612388566362;10265.897341026493;10307.484625768831;10651.627193048205;10712.89287107119;10893.019628233496;9679.41923484565;9693.563789629625;9742.707718768448;9755.609775608897;9766.804663906823;6574.030942446249;7100.218975912749;7183.245759195209;7369.48925470288;7477.140128885248;7073.858522829618;7202.099737532809;7532.284432978859;7610.885836712565;7631.771046868603 12:28:10 INFO - PID 2245 | |4;prototype.html;468.60871172570904;471.86551832727656;503.0944300259532;506.2760252838442;511.1489370198617;5614.915776263354;6099.451049405553;6227.47066499347;6401.871962560746;6465.612063276207;5469.781208751655;6205.937940620593;6276.089966954789;6285.7799977000695;6344.953082741354;4167.520735115462;4522.751248681321;4755.9286610700865;4778.498257682945;4787.976060119695;4456.888577785565;4643.907121857561;4789.712617242959;4805.615550755949;4812.783424745878;4762.380890484233;4764.142454358214;4765.8570242892765;4796.026463592578;4803.853475973243;4550.5449455054395;4726.881827954294;4756.809727610891;4800.831970881038;4815.398075240578;4209.137126888981;4210.115875666113;4210.5157906840495;4219.177260434217;4236.237477254093;6170.598911070766;6190.721417536221;6224.284207316171;6230.719617617173;6270.717817698169;4998.200287953946;5664.546836253131;5718.34239062509;5736.7952729926765;5743.339515955677;5558.44415558445;6207.951376045894;6616.974368972817;6804.76183333581;6837.965810170967;4138.820436175675;4399.812050746281;4763.166445871993;4858.295547145644;4877.317175595419;6392.456641185505;7201.279872012757;7295.7811265661585;7301.780946571558;7302.342789148949;4964.478729733398;4984.177610694195;4984.77568509418;5008.449070602231;5009.248612708123;4719.9852031813325;4788.018456216468;5047.722375269322;5082.440931497532;5090.592752579785;5297.675581104724;5482.753276102585;5730.226419433407;5768.971155144198;5795.913061304083;6239.719212635442;6816.386525212754;6817.659117044128;6879.346462086094;6939.410150137295;1449.3767679897744;1557.470460043568;1633.4691225351808;1741.7561541384216;1742.5120966129505;1165.5174478361207;1166.1953252255917;1168.3165348271186;1168.8772678868766;1186.9525218991316;5422.40353561108;5460.426655201226;5969.253843269591;6252.342754329483;6277.8744425111245;5602.635828671123;6800.52396332252;6996.8950465743055;7088.149422069281;7090.12111790376;5541.141123125922;5602.859928501739;5684.403137670485;6194.0708893666315;6421.229452465734;3372.2918187180967;3640.8179591020344;3657.4148136298595;3689.0592898810633;3695.279420512974;6455.7740479083;6457.128287681103;7194.496385252982;7194.604296763624;7218.386437152901;7163.42692584592;7182.138143422718;7194.856102877913;7208.125887269348;7267;6401.9436792928645;6794.898076528856;7005.22942476317;7236.0231368766645;7302.890456643155;5457.508824205841;6418.65208306261;6942.618156001468;7038.859222815618;7066.540674856118;6107.9083813741945;6920.688569014507;6937.549059311129;6939.375456208763;6967.965160174167;6289.339619339903;6743.35938085881;7151.4636402269;7238.27617238293;7268.491205615556;3841.3853783394525;4894.412670479565;4933.136701077226;4964.67729597575;4977.502249774993;6737.831554211184;7031.22656507794;7078.43372530186;7081.733845169496;7123.041163679823;6215.627062376272;6386.457151142205;6811.523193376416;6822.727090916308;6852.349026842442;5990.610610310316;6792.592444453348;6812.0122582226295;6835.624040677811;6847.383735463832;6602.038776733922;6661.367170118831;6669.333066693486;6685.426110947223;6691.732330706717;5865.5600829937075;6683.866322673616;6746.662666866735;6833.180018397626;6930;6578.868422631616;6625.503087268377;6638.502112341497;6638.701258443285;6669.4997875160525 12:28:10 INFO - PID 2245 | |5;yui.html;729.7701224114404;837.606325027237;855.867340562213;871.8082021955167;872.4242000279817;16962.40631577895;17240.482785516455;17424.82575174248;17431.302747890084;17470.388536401246;14429.783553246709;14487.275636218199;14812;15017.32422041008;15021.92489037556;741.0958630470831;1021.6781713760141;1021.8416145497459;1023.9795204095914;1024.6413755185663;1061.4957894999852;1066.8773091094547;1082.6697857153604;1082.8954466444222;1083.1118482844072;1096.270979798433;1098.8077086509868;1115.8772535021142;1116.966805704724;1117.3016864459712;1019.2966852871494;1046.8115739166947;1090.7109615951779;1107.7175320293354;1109.4785450838094;799.2407213147475;800.0479429478902;803.3573141486787;804.6017221475348;809.9149589293098;18322.26710931561;18370.540736481562;18385.816141838543;18474.630507389913;18589.62820743591;16928.49214523566;17990.830596011216;18085.819141808544;18116.094195290316;18121.36575219861;14736.115833050051;14863.777043344358;15184.544463666107;15266.847331526653;15272.770908436383;3620.619834917322;3685.244524872395;3701.000729802938;3706.6478684525196;3713.832877520518;153995.23002385028;160432;167222.16388918099;170481;172589;13061;14314.427422903176;14560.781588276184;14587.197704126374;14589.12465252067;26696.59955100675;27104.59343109855;27313.590296145572;27462.862685686443;27614.44771104567;8257.339412846957;8311.667533298722;8314.127016663164;8333.708320208885;8356.540390278471;4122.6083522065355;4141.233871733679;4180.958190418118;4181.560936101726;4188.225178342016;4897.951020489821;4962.8759281018265;4967.5529202371235;4980.850574482772;4998.6500944933505;1059.7085801404676;1100.625787232345;1123.1015187849687;1147.9827802582968;1148.5176225985106;634.6953462338104;666.2604508995012;672.6569449580749;696.6621188723465;696.7909627111948;11013.66035392734;11987.22083064598;12577.93711031439;13288.867111328957;14644.12135271887;634.3624657219557;648.7664440801308;698.678607840389;707.0243064570859;707.8619669164464;5547;6085.786997455068;6180.412860778219;6418.486521078303;6551.886179349427;147905.52094478917;148311;151196.73204901937;153405;155943;115386;123636;123731.38134309271;126344;127755.3612231933;104359.4782026085;107838.46080769546;108720;108806.82386352228;113096.43451782853;12540.623781286577;12702.491900323883;12854;12981.415836287579;13083.738325233633;47418.76290618525;48238.5176148234;51759.74120129375;52704.47295526996;53466.73266633642;64756;67629;68675.31324686889;79947.20052799398;94413;15311.234438278265;17769.555761106076;17803;17924.283028678707;18614.51083913311;18235.908820455814;19292.614147716686;19814.108365123317;20155;20410.59178816445;18268.36060737868;18275.725864111784;18734.063296835375;18768.7184692227;18918.337858175448;17285.049322287396;18116.094195290447;18275;18868.05659717036;18969.810301896803;17739.556511087325;17832.19755111049;18252.26990920348;18358;18428.89426634406;12335.444904979477;12711.42798574085;12841.614751557468;13083.41124649374;13143.408546615234;11882.762344752884;13516;14746.336414861571;14985.550433487013;15137.470188543348 12:28:10 INFO - PID 2245 | __end_tp_report 12:28:10 INFO - PID 2245 | __start_cc_report 12:28:10 INFO - PID 2245 | _x_x_mozilla_cycle_collect,421 12:28:10 INFO - PID 2245 | __end_cc_report 12:28:10 INFO - PID 2245 | __startTimestamp1498073290156__endTimestamp 12:28:10 INFO - PID 2245 | 12:28:10 INFO - PID 2245 | ------- Summary: start ------- 12:28:10 INFO - PID 2245 | Number of tests: 6 12:28:10 INFO - PID 2245 | 12:28:10 INFO - PID 2245 | [#0] dojo.html Cycles:165 Average:6331.59 Median:6548.77 stddev:811.94 (12.4%) stddev-sans-first:775.75 12:28:10 INFO - PID 2245 | Values: 3174.7 3520.5 3526.8 3599.0 3606.7 6197.5 6238.2 6290.9 6294.9 6323.2 6267.5 6337.7 6343.6 6350.0 6359.7 5546.8 5565.0 5596.1 5670.6 5683.8 5474.6 5552.6 5584.4 5595.3 5631.5 4725.9 5480.5 5490.3 5545.1 5561.9 5638.9 5640.6 5641.0 5650.0 5691.5 5429.6 5543.8 5584.5 5603.4 5610.1 6162.3 6321.2 6343.7 6365.6 6404.9 5860.6 6749.4 6813.9 6834.0 6846.1 7001.8 7010.1 7022.3 7043.3 7087.6 5383.7 6422.6 6500.5 6504.4 6505.5 6977.2 7042.3 7049.4 7071.7 7076.7 6513.2 6531.4 6686.2 6746.5 6840.0 7030.6 7055.7 7057.5 7067.6 7074.3 6672.9 6930.4 6946.7 6995.6 6999.3 6776.6 6788.2 6824.2 6839.8 6862.1 5397.2 5418.8 5419.8 5443.6 5482.5 4857.9 4864.8 4872.8 4882.6 4887.9 5968.9 6422.2 6496.3 6543.6 6554.0 5849.5 6822.5 7057.3 7066.8 7072.8 6369.2 6390.4 6403.2 6499.4 6515.3 6021.5 6118.6 6151.3 6157.1 6162.1 7301.2 7314.8 7316.9 7317.2 7317.5 7066.2 7072.8 7087.8 7096.3 7097.0 7075.8 7265.0 7267.9 7289.6 7295.3 5187.5 6777.7 6820.9 6935.2 6936.3 5872.4 6327.5 6987.8 7025.9 7091.9 6804.6 6815.6 6822.7 6827.9 6839.5 6568.2 6571.7 6670.7 6703.0 6712.3 6548.8 6670.0 6723.0 6723.1 6732.1 6868.0 6869.7 6876.6 6878.0 6880.3 5754.9 6596.3 6662.4 6707.0 6763.7 12:28:10 INFO - PID 2245 | 12:28:10 INFO - PID 2245 | [#1] ext.html Cycles:180 Average:46636.64 Median:340991.00 stddev:85574.99 (25.1%) stddev-sans-first:85746.19 12:28:10 INFO - PID 2245 | Values: 912.7 946.7 948.4 966.0 966.0 38474.0 39161.8 39259.2 39368.0 39389.6 37966.2 38062.0 38117.9 38181.6 38217.0 7756.4 7898.2 7911.5 7932.0 7937.4 7806.6 7845.0 7896.6 7913.3 7943.3 7860.1 7865.6 7896.9 7898.8 7904.9 8505.6 8669.1 8741.6 8755.6 8764.6 7383.3 8417.7 8471.3 8498.2 8505.6 29809.7 35333.6 35642.3 35694.8 35769.0 3624.8 3643.3 3683.0 3691.9 3700.9 26329.5 30129.0 30198.8 30252.8 30276.1 3085.9 3161.8 3181.5 3192.1 3208.9 317431.0 323942.4 324098.0 331964.0 332519.0 48365.8 49222.0 49379.8 49403.8 49448.3 51587.7 51675.0 51832.2 51897.7 51971.0 12022.6 14712.0 14729.9 14740.6 14754.5 12001.9 12024.5 12026.5 12044.8 12075.2 13674.5 13704.7 13715.3 13779.9 13786.9 5957.8 5973.0 5996.8 6045.2 6050.7 999.9 1003.2 1003.7 1049.8 1064.5 19956.6 22573.7 22721.4 22820.7 24190.0 1079.4 1092.2 1125.8 1141.5 1148.9 12320.9 12325.3 12347.4 12350.9 12447.0 340829.0 341153.0 345201.0 345487.0 345668.0 51938.7 52171.2 52400.0 52458.5 52477.5 1416.3 1489.0 1490.5 1495.1 1509.4 21773.8 22127.6 22170.0 22334.7 22659.0 37476.3 37703.6 37724.8 37790.8 37821.4 303398.0 303802.0 304919.0 305054.0 305237.0 29155.1 29632.3 29738.4 32951.7 33125.7 37477.0 37650.0 41117.2 41216.4 42922.4 27296.6 28013.0 28052.4 28770.7 29182.0 28408.1 29297.9 29797.3 29848.7 29931.0 35756.6 35947.3 36105.0 36415.7 36970.3 23406.5 26262.9 28880.1 30157.7 31977.0 24305.0 24619.4 24643.5 24711.5 24833.8 12:28:10 INFO - PID 2245 | 12:28:10 INFO - PID 2245 | [#2] jquery.html Cycles:180 Average:42284.59 Median:12237.04 stddev:105734.96 (864.1%) stddev-sans-first:105985.19 12:28:10 INFO - PID 2245 | Values: 571.0 571.6 574.1 575.9 588.0 23554.0 24118.6 24349.5 24389.9 24441.0 20816.5 23195.8 24995.5 26977.0 27078.7 6561.3 7069.3 7078.2 7089.0 7092.0 6180.9 6935.3 6957.2 6996.9 7020.0 6342.9 6632.5 6742.5 6760.7 6962.9 6756.8 6843.1 6843.3 6844.3 6849.2 5557.2 6324.1 6324.2 6330.1 6347.5 26285.6 29655.3 30791.8 31555.2 31990.9 2249.5 2283.3 2294.1 2300.4 2301.1 16575.3 19430.9 19731.0 19819.7 19843.6 1895.4 2210.8 2232.4 2277.3 2283.1 128843.4 216425.0 218150.0 220627.0 222113.0 13357.4 16717.7 16768.5 16999.9 17236.1 22491.9 22518.3 22584.0 22607.4 22615.4 10166.7 10206.8 10293.4 10308.8 10430.9 9331.9 9336.8 9351.7 9365.7 9392.8 8037.0 9524.7 9630.1 9718.7 9772.6 2023.9 2331.6 2656.4 2815.3 2847.6 957.9 976.4 979.4 998.7 1012.9 21991.6 22162.8 22179.0 22241.7 22975.5 1010.4 1014.5 1031.2 1049.3 1056.4 11105.4 11342.7 11461.7 11464.4 11988.1 604356.0 604769.0 614907.0 616567.0 617712.0 160659.0 163667.0 164401.5 165123.0 165916.0 1152.0 1172.3 1199.9 1209.7 1210.2 15630.8 19140.9 19301.8 19477.0 19764.6 72926.0 72953.3 73421.0 73790.0 75162.0 94980.6 99310.0 99519.0 100355.0 100816.0 12335.5 12469.3 12528.7 12605.9 12613.6 18747.4 18762.9 18791.2 18856.7 19230.7 14666.9 15217.8 15356.2 15514.5 15550.7 13635.8 13677.7 13777.8 14676.9 14902.4 16426.9 16459.3 16471.0 16611.2 16614.1 17099.4 17748.5 17798.2 18186.9 18249.5 12192.5 15870.2 15907.3 16129.0 16130.6 12:28:10 INFO - PID 2245 | 12:28:10 INFO - PID 2245 | [#3] mootools.html Cycles:180 Average:9109.12 Median:2621.15 stddev:6289.12 (239.9%) stddev-sans-first:6280.25 12:28:10 INFO - PID 2245 | Values: 1423.5 1539.7 1559.1 1570.5 1594.6 14979.7 15576.5 15637.9 15640.8 15653.5 12933.0 13272.2 13360.7 13386.3 14900.2 2424.9 2931.2 2932.8 2935.9 2936.7 2597.3 2620.8 2621.5 2624.2 2641.8 2850.4 2866.5 2890.3 2890.5 2900.7 2539.8 2554.9 2558.0 2562.7 2564.4 2459.6 2813.3 2827.8 2830.9 2844.4 14757.0 14789.9 14974.5 14991.9 14993.3 7154.2 8113.2 8437.2 8439.4 8457.1 4923.1 5139.9 5184.7 5313.9 5322.1 1508.3 1513.7 1518.5 1535.6 1541.9 22608.3 23969.5 24016.2 24018.9 24057.4 7822.3 7849.0 7860.3 7893.6 8008.3 18869.2 18873.0 18902.1 18905.6 18956.6 8387.0 8407.2 8463.4 8481.2 8490.1 6013.9 7430.7 7521.8 7559.1 7601.7 5648.6 5843.8 5969.5 6032.0 6070.6 1848.2 1865.4 1871.1 1871.7 1890.0 788.6 826.6 861.4 862.9 871.5 7184.1 7304.9 7345.6 7584.2 7621.0 692.8 713.0 714.2 714.7 722.5 4215.5 4429.9 4481.4 5655.1 5793.7 19562.5 19823.5 20166.6 20237.0 20300.9 19078.5 19781.4 19817.6 19992.6 20212.4 9557.2 9920.1 10192.7 10255.4 10292.4 8168.9 8480.5 8617.8 8639.5 8971.0 17688.2 18085.5 18205.6 18483.4 18491.0 17370.7 18477.0 18584.9 18757.3 18799.0 10046.4 12572.7 12612.7 12787.0 13272.6 13317.6 13480.7 13793.8 13864.9 13993.4 8492.7 10476.6 10541.5 10959.6 11074.6 10265.9 10307.5 10651.6 10712.9 10893.0 9679.4 9693.6 9742.7 9755.6 9766.8 6574.0 7100.2 7183.2 7369.5 7477.1 7073.9 7202.1 7532.3 7610.9 7631.8 12:28:10 INFO - PID 2245 | 12:28:10 INFO - PID 2245 | [#4] prototype.html Cycles:180 Average:5531.73 Median:6180.66 stddev:1672.09 (27.1%) stddev-sans-first:1633.02 12:28:10 INFO - PID 2245 | Values: 468.6 471.9 503.1 506.3 511.1 5614.9 6099.5 6227.5 6401.9 6465.6 5469.8 6205.9 6276.1 6285.8 6345.0 4167.5 4522.8 4755.9 4778.5 4788.0 4456.9 4643.9 4789.7 4805.6 4812.8 4762.4 4764.1 4765.9 4796.0 4803.9 4550.5 4726.9 4756.8 4800.8 4815.4 4209.1 4210.1 4210.5 4219.2 4236.2 6170.6 6190.7 6224.3 6230.7 6270.7 4998.2 5664.5 5718.3 5736.8 5743.3 5558.4 6208.0 6617.0 6804.8 6838.0 4138.8 4399.8 4763.2 4858.3 4877.3 6392.5 7201.3 7295.8 7301.8 7302.3 4964.5 4984.2 4984.8 5008.4 5009.2 4720.0 4788.0 5047.7 5082.4 5090.6 5297.7 5482.8 5730.2 5769.0 5795.9 6239.7 6816.4 6817.7 6879.3 6939.4 1449.4 1557.5 1633.5 1741.8 1742.5 1165.5 1166.2 1168.3 1168.9 1187.0 5422.4 5460.4 5969.3 6252.3 6277.9 5602.6 6800.5 6996.9 7088.1 7090.1 5541.1 5602.9 5684.4 6194.1 6421.2 3372.3 3640.8 3657.4 3689.1 3695.3 6455.8 6457.1 7194.5 7194.6 7218.4 7163.4 7182.1 7194.9 7208.1 7267.0 6401.9 6794.9 7005.2 7236.0 7302.9 5457.5 6418.7 6942.6 7038.9 7066.5 6107.9 6920.7 6937.5 6939.4 6968.0 6289.3 6743.4 7151.5 7238.3 7268.5 3841.4 4894.4 4933.1 4964.7 4977.5 6737.8 7031.2 7078.4 7081.7 7123.0 6215.6 6386.5 6811.5 6822.7 6852.3 5990.6 6792.6 6812.0 6835.6 6847.4 6602.0 6661.4 6669.3 6685.4 6691.7 5865.6 6683.9 6746.7 6833.2 6930.0 6578.9 6625.5 6638.5 6638.7 6669.5 12:28:10 INFO - PID 2245 | 12:28:10 INFO - PID 2245 | [#5] yui.html Cycles:180 Average:27194.55 Median:17878.24 stddev:42223.46 (236.2%) stddev-sans-first:42295.15 12:28:10 INFO - PID 2245 | Values: 729.8 837.6 855.9 871.8 872.4 16962.4 17240.5 17424.8 17431.3 17470.4 14429.8 14487.3 14812.0 15017.3 15021.9 741.1 1021.7 1021.8 1024.0 1024.6 1061.5 1066.9 1082.7 1082.9 1083.1 1096.3 1098.8 1115.9 1117.0 1117.3 1019.3 1046.8 1090.7 1107.7 1109.5 799.2 800.0 803.4 804.6 809.9 18322.3 18370.5 18385.8 18474.6 18589.6 16928.5 17990.8 18085.8 18116.1 18121.4 14736.1 14863.8 15184.5 15266.8 15272.8 3620.6 3685.2 3701.0 3706.6 3713.8 153995.2 160432.0 167222.2 170481.0 172589.0 13061.0 14314.4 14560.8 14587.2 14589.1 26696.6 27104.6 27313.6 27462.9 27614.4 8257.3 8311.7 8314.1 8333.7 8356.5 4122.6 4141.2 4181.0 4181.6 4188.2 4898.0 4962.9 4967.6 4980.9 4998.7 1059.7 1100.6 1123.1 1148.0 1148.5 634.7 666.3 672.7 696.7 696.8 11013.7 11987.2 12577.9 13288.9 14644.1 634.4 648.8 698.7 707.0 707.9 5547.0 6085.8 6180.4 6418.5 6551.9 147905.5 148311.0 151196.7 153405.0 155943.0 115386.0 123636.0 123731.4 126344.0 127755.4 104359.5 107838.5 108720.0 108806.8 113096.4 12540.6 12702.5 12854.0 12981.4 13083.7 47418.8 48238.5 51759.7 52704.5 53466.7 64756.0 67629.0 68675.3 79947.2 94413.0 15311.2 17769.6 17803.0 17924.3 18614.5 18235.9 19292.6 19814.1 20155.0 20410.6 18268.4 18275.7 18734.1 18768.7 18918.3 17285.0 18116.1 18275.0 18868.1 18969.8 17739.6 17832.2 18252.3 18358.0 18428.9 12335.4 12711.4 12841.6 13083.4 13143.4 11882.8 13516.0 14746.3 14985.6 15137.5 12:28:10 INFO - PID 2245 | -------- Summary: end -------- 12:28:10 INFO - PID 2245 | 12:28:10 INFO - TEST-INFO | 2245: exit 0 12:28:10 INFO - TEST-OK | dromaeo_css | took 1118479ms 12:28:10 INFO - TEST-START | kraken 12:28:10 INFO - operating with platform_type : mac_ 12:28:10 INFO - Initialising browser for kraken test... 12:28:10 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56889/getInfo.html -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpoYh4X5/profile 12:28:10 INFO - TEST-INFO | started process 2250 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56889/getInfo.html -foreground) 12:28:17 INFO - TEST-INFO | 2250: exit 0 12:28:17 INFO - Browser initialized. 12:28:17 INFO - Running cycle 1/1 for kraken test... 12:28:17 INFO - TEST-INFO | started process 2256 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpoYh4X5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:28:19 INFO - PID 2256 | 2017-06-21 12:28:19.390 plugin-container[2257:28243] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 12:28:19 INFO - PID 2256 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:19 INFO - PID 2256 | 2017-06-21 12:28:19.391 plugin-container[2257:28243] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d13, name = 'com.apple.CFPasteboardClient' 12:28:19 INFO - PID 2256 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:19 INFO - PID 2256 | 2017-06-21 12:28:19.391 plugin-container[2257:28243] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:28:20 INFO - PID 2256 | 2017-06-21 12:28:20.203 plugin-container[2258:28300] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 12:28:20 INFO - PID 2256 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:20 INFO - PID 2256 | 2017-06-21 12:28:20.204 plugin-container[2258:28300] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 12:28:20 INFO - PID 2256 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:20 INFO - PID 2256 | 2017-06-21 12:28:20.204 plugin-container[2258:28300] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:28:29 INFO - PID 2256 | RSS: Main: 249257984 12:28:29 INFO - PID 2256 | 12:28:32 INFO - PID 2256 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56889/tests/kraken/driver.html?testName=audio-beat-detection) 12:28:33 INFO - PID 2256 | RSS: Main: 259805184 12:28:33 INFO - PID 2256 | 12:28:35 INFO - PID 2256 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56889/tests/kraken/driver.html?testName=audio-dft) 12:28:36 INFO - PID 2256 | RSS: Main: 269426688 12:28:36 INFO - PID 2256 | 12:28:39 INFO - PID 2256 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56889/tests/kraken/driver.html?testName=audio-fft) 12:28:40 INFO - PID 2256 | RSS: Main: 271855616 12:28:40 INFO - PID 2256 | 12:28:42 INFO - PID 2256 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56889/tests/kraken/driver.html?testName=audio-oscillator) 12:28:42 INFO - PID 2256 | RSS: Main: 273707008 12:28:42 INFO - PID 2256 | 12:28:44 INFO - PID 2256 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56889/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:28:45 INFO - PID 2256 | RSS: Main: 274350080 12:28:45 INFO - PID 2256 | 12:28:48 INFO - PID 2256 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56889/tests/kraken/driver.html?testName=imaging-darkroom) 12:28:49 INFO - PID 2256 | RSS: Main: 281714688 12:28:49 INFO - PID 2256 | 12:28:52 INFO - PID 2256 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56889/tests/kraken/driver.html?testName=imaging-desaturate) 12:28:52 INFO - PID 2256 | RSS: Main: 286633984 12:28:52 INFO - PID 2256 | 12:28:55 INFO - PID 2256 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56889/tests/kraken/driver.html?testName=json-parse-financial) 12:28:56 INFO - PID 2256 | RSS: Main: 285798400 12:28:56 INFO - PID 2256 | 12:28:57 INFO - PID 2256 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56889/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:28:58 INFO - PID 2256 | RSS: Main: 286179328 12:28:58 INFO - PID 2256 | 12:29:00 INFO - PID 2256 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56889/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:29:01 INFO - PID 2256 | RSS: Main: 285843456 12:29:01 INFO - PID 2256 | 12:29:03 INFO - PID 2256 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56889/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:29:03 INFO - PID 2256 | RSS: Main: 285335552 12:29:03 INFO - PID 2256 | 12:29:06 INFO - PID 2256 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56889/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:29:07 INFO - PID 2256 | RSS: Main: 285597696 12:29:07 INFO - PID 2256 | 12:29:10 INFO - PID 2256 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56889/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:29:11 INFO - PID 2256 | RSS: Main: 285687808 12:29:11 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | Cycle 1(1): loaded http://localhost:56889/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56889/tests/kraken/driver.html?testName=ai-astar) 12:29:13 INFO - PID 2256 | RSS: Main: 287334400 12:29:13 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | __start_tp_report 12:29:13 INFO - PID 2256 | _x_x_mozilla_page_load 12:29:13 INFO - PID 2256 | _x_x_mozilla_page_load_details 12:29:13 INFO - PID 2256 | |i|pagename|runs| 12:29:13 INFO - PID 2256 | |0;ai-astar;104;115;114;113;120;116;113;118;114;114 12:29:13 INFO - PID 2256 | |1;audio-beat-detection;134;113;150;141;123;129;108;147;146;152 12:29:13 INFO - PID 2256 | |2;audio-dft;208;204;199;201;209;226;230;206;202;212 12:29:13 INFO - PID 2256 | |3;audio-fft;73;86;102;188;80;84;57;62;127;58 12:29:13 INFO - PID 2256 | |4;audio-oscillator;69;69;113;68;80;80;113;73;73;115 12:29:13 INFO - PID 2256 | |5;imaging-gaussian-blur;90;90;92;93;92;106;130;84;131;132 12:29:13 INFO - PID 2256 | |6;imaging-darkroom;100;98;98;97;99;96;97;107;97;98 12:29:13 INFO - PID 2256 | |7;imaging-desaturate;132;96;148;145;93;90;92;76;143;71 12:29:13 INFO - PID 2256 | |8;json-parse-financial;51;50;53;50;53;54;51;51;53;54 12:29:13 INFO - PID 2256 | |9;json-stringify-tinderbox;52;54;76;52;48;81;47;56;60;76 12:29:13 INFO - PID 2256 | |10;stanford-crypto-aes;81;81;81;87;102;108;70;107;103;103 12:29:13 INFO - PID 2256 | |11;stanford-crypto-ccm;135;146;135;135;134;164;140;140;140;169 12:29:13 INFO - PID 2256 | |12;stanford-crypto-pbkdf2;218;210;178;185;207;230;205;157;196;166 12:29:13 INFO - PID 2256 | |13;stanford-crypto-sha256-iterative;71;95;83;67;62;67;66;80;77;90 12:29:13 INFO - PID 2256 | __end_tp_report 12:29:13 INFO - PID 2256 | __start_cc_report 12:29:13 INFO - PID 2256 | _x_x_mozilla_cycle_collect,1088 12:29:13 INFO - PID 2256 | __end_cc_report 12:29:13 INFO - PID 2256 | __startTimestamp1498073353345__endTimestamp 12:29:13 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | ------- Summary: start ------- 12:29:13 INFO - PID 2256 | Number of tests: 14 12:29:13 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.50 stddev:4.20 (3.7%) stddev-sans-first:2.39 12:29:13 INFO - PID 2256 | Values: 104.0 115.0 114.0 113.0 120.0 116.0 113.0 118.0 114.0 114.0 12:29:13 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | [#1] audio-beat-detection Cycles:10 Average:134.30 Median:143.50 stddev:15.65 (10.9%) stddev-sans-first:16.60 12:29:13 INFO - PID 2256 | Values: 134.0 113.0 150.0 141.0 123.0 129.0 108.0 147.0 146.0 152.0 12:29:13 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | [#2] audio-dft Cycles:10 Average:209.70 Median:208.50 stddev:10.45 (5.0%) stddev-sans-first:11.06 12:29:13 INFO - PID 2256 | Values: 208.0 204.0 199.0 201.0 209.0 226.0 230.0 206.0 202.0 212.0 12:29:13 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | [#3] audio-fft Cycles:10 Average:91.70 Median:67.50 stddev:40.04 (59.3%) stddev-sans-first:41.89 12:29:13 INFO - PID 2256 | Values: 73.0 86.0 102.0 188.0 80.0 84.0 57.0 62.0 127.0 58.0 12:29:13 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | [#4] audio-oscillator Cycles:10 Average:85.30 Median:71.00 stddev:20.02 (28.2%) stddev-sans-first:20.34 12:29:13 INFO - PID 2256 | Values: 69.0 69.0 113.0 68.0 80.0 80.0 113.0 73.0 73.0 115.0 12:29:13 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | [#5] imaging-gaussian-blur Cycles:10 Average:104.00 Median:90.00 stddev:19.42 (21.6%) stddev-sans-first:19.93 12:29:13 INFO - PID 2256 | Values: 90.0 90.0 92.0 93.0 92.0 106.0 130.0 84.0 131.0 132.0 12:29:13 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | [#6] imaging-darkroom Cycles:10 Average:98.70 Median:97.50 stddev:3.13 (3.2%) stddev-sans-first:3.28 12:29:13 INFO - PID 2256 | Values: 100.0 98.0 98.0 97.0 99.0 96.0 97.0 107.0 97.0 98.0 12:29:13 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | [#7] imaging-desaturate Cycles:10 Average:108.60 Median:83.00 stddev:30.02 (36.2%) stddev-sans-first:30.62 12:29:13 INFO - PID 2256 | Values: 132.0 96.0 148.0 145.0 93.0 90.0 92.0 76.0 143.0 71.0 12:29:13 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:53.00 stddev:1.56 (2.9%) stddev-sans-first:1.62 12:29:13 INFO - PID 2256 | Values: 51.0 50.0 53.0 50.0 53.0 54.0 51.0 51.0 53.0 54.0 12:29:13 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:58.00 stddev:12.67 (21.9%) stddev-sans-first:13.09 12:29:13 INFO - PID 2256 | Values: 52.0 54.0 76.0 52.0 48.0 81.0 47.0 56.0 60.0 76.0 12:29:13 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | [#10] stanford-crypto-aes Cycles:10 Average:92.30 Median:75.50 stddev:13.72 (18.2%) stddev-sans-first:13.93 12:29:13 INFO - PID 2256 | Values: 81.0 81.0 81.0 87.0 102.0 108.0 70.0 107.0 103.0 103.0 12:29:13 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | [#11] stanford-crypto-ccm Cycles:10 Average:143.80 Median:140.00 stddev:12.56 (9.0%) stddev-sans-first:12.91 12:29:13 INFO - PID 2256 | Values: 135.0 146.0 135.0 135.0 134.0 164.0 140.0 140.0 140.0 169.0 12:29:13 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.20 Median:206.00 stddev:23.33 (11.3%) stddev-sans-first:23.24 12:29:13 INFO - PID 2256 | Values: 218.0 210.0 178.0 185.0 207.0 230.0 205.0 157.0 196.0 166.0 12:29:13 INFO - PID 2256 | 12:29:13 INFO - PID 2256 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.80 Median:78.50 stddev:11.08 (14.1%) stddev-sans-first:11.62 12:29:13 INFO - PID 2256 | Values: 71.0 95.0 83.0 67.0 62.0 67.0 66.0 80.0 77.0 90.0 12:29:13 INFO - PID 2256 | -------- Summary: end -------- 12:29:13 INFO - PID 2256 | 12:29:14 INFO - TEST-INFO | 2256: exit 0 12:29:14 INFO - TEST-OK | kraken | took 63522ms 12:29:14 INFO - SUITE-END | took 1182s 12:29:14 INFO - Completed test suite (00:19:42) 12:29:14 INFO - javascript benchmark 12:29:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8625.348805770831, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3174.6507884132743, 3520.4719292106197, 3526.8060256685903, 3598.999999999998, 3606.7475276730625, 6197.535184861136, 6238.189035425394, 6290.874182516359, 6294.937050629493, 6323.20959880015, 6267.467265282445, 6337.683115844201, 6343.619382837033, 6349.968250158754, 6359.68201589921, 5546.750396232179, 5565.0, 5596.104623260279, 5670.574706896979, 5683.772649094031, 5474.562035037187, 5552.639078459907, 5584.385717571064, 5595.328560572737, 5631.521320687726, 4725.929111063337, 5480.534154596864, 5490.3411590609385, 5545.140503222007, 5561.944380556183, 5638.887222255573, 5640.633358831663, 5641.041023026054, 5650.0, 5691.51622112121, 5429.619926605139, 5543.77824887004, 5584.5253153482, 5603.3836278009385, 5610.130429783349, 6162.291336496315, 6321.209848768904, 6343.682815859234, 6365.554411191219, 6404.903926441107, 5860.648361098348, 6749.392554670103, 6813.863722725568, 6834.0, 6846.144231971004, 7001.754938577125, 7010.088688470466, 7022.332878376546, 7043.260457651924, 7087.574745515234, 5383.676979381171, 6422.646754428458, 6500.512461565401, 6504.414602685781, 6505.544611877218, 6977.197622273402, 7042.260562640951, 7049.436045116378, 7071.717131314793, 7076.681549330293, 6513.2184137902805, 6531.444827189742, 6686.1642294713165, 6746.4940129489505, 6839.965800170968, 7030.613316267552, 7055.717771289091, 7057.50597458173, 7067.57594544329, 7074.257202993715, 6672.866542669217, 6930.3762661360715, 6946.7221311148, 6995.580265184105, 6999.265077166824, 6776.559523630948, 6788.151481064867, 6824.18109826824, 6839.760608378683, 6862.073620061327, 5397.163439666858, 5418.756155972991, 5419.840469606353, 5443.591730620219, 5482.451754824486, 4857.878553036202, 4864.829730959399, 4872.756362181876, 4882.633802464758, 4887.853364399002, 5968.850778730566, 6422.2293324800385, 6496.285408605049, 6543.640099794462, 6554.016897465323, 5849.502792262705, 6822.522423430312, 7057.258987806309, 7066.787996360117, 7072.823179420556, 6369.172007638977, 6390.4248617624635, 6403.231612206565, 6499.350064993463, 6515.283318834924, 6021.5182785377065, 6118.558266176196, 6151.292601350902, 6157.107219453154, 6162.137300777805, 7301.233370496021, 7314.780556583311, 7316.890246646198, 7317.2316906726155, 7317.487775855639, 7066.222715501188, 7072.787816365517, 7087.787366379017, 7096.325849044332, 7097.041899343522, 7075.82310442243, 7265.0, 7267.890981635385, 7289.599072051088, 7295.343419092307, 5187.455317191791, 6777.661116944035, 6820.897686534607, 6935.167779866449, 6936.271691472524, 5872.412758724094, 6327.493800495858, 6987.825304367431, 7025.929740702528, 7091.893621595577, 6804.591724496546, 6815.591064536144, 6822.692978816064, 6827.897581536182, 6839.487038472035, 6568.1789776277965, 6571.671416429255, 6670.699818508276, 6702.966485167543, 6712.2616512184595, 6548.770793022221, 6670.0, 6722.991551267349, 6723.125993620798, 6732.091167692299, 6868.038474613658, 6869.690863911036, 6876.587404755731, 6878.0, 6880.311968803079, 5754.9136762947755, 6596.3073877244115, 6662.433693135944, 6707.0, 6763.695633696397], "value": 6274.227927652977, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [912.6714382822183, 946.7443790176652, 948.4167237149151, 965.9806803863919, 966.0436168193481, 38474.03814904625, 39161.80419097904, 39259.21481570367, 39367.99999999996, 39389.606103939026, 37966.24067518655, 38062.0, 38117.856464306045, 38181.61818381815, 38217.0, 7756.3794896408435, 7898.249666281723, 7911.485753426037, 7932.0, 7937.44437889348, 7806.60966951653, 7845.0, 7896.605169741519, 7913.28780409763, 7943.324817390529, 7860.056793184797, 7865.646045927947, 7896.881546776803, 7898.842023159534, 7904.881426778603, 8505.574721263944, 8669.133086691343, 8741.562921853882, 8755.562221888911, 8764.649414023432, 7383.261673832628, 8417.747467575982, 8471.279941205004, 8498.150184981514, 8505.574721263913, 29809.701902981127, 35333.64666353355, 35642.28715425703, 35694.82152589221, 35769.0, 3624.818759062063, 3643.2713457308646, 3683.0055884910926, 3691.907702307437, 3700.9259814803822, 26329.473410531682, 30129.0, 30198.84900575505, 30252.8487357564, 30276.0917172483, 3085.922851928697, 3161.8419079046184, 3181.5227715842807, 3192.1381227068796, 3208.903732888017, 317431.0, 323942.3802880994, 324097.9999999976, 331964.0, 332519.0, 48365.758171208916, 49222.0, 49379.753101234266, 49403.752981234866, 49448.26931057396, 51587.74206128946, 51675.0, 51832.22251666228, 51897.740511297205, 51971.0, 12022.63932082039, 14711.970162088764, 14729.852701473063, 14740.631484212981, 14754.483593074405, 12001.879981200076, 12024.45889934955, 12026.518939242507, 12044.81932771009, 12075.215111017756, 13674.453021879212, 13704.65738356549, 13715.314234288246, 13779.931100344635, 13786.862131378759, 5957.791477298274, 5973.0, 5996.790112346047, 6045.183900173508, 6050.699099693587, 999.8900120986685, 1003.2425518733455, 1003.678822776719, 1049.7665243339045, 1064.499685147979, 19956.600867982557, 22573.661395079085, 22721.431964201027, 22820.65769013466, 24190.0, 1079.433297518809, 1092.1754075672816, 1125.774845030997, 1141.50915106505, 1148.8851114888444, 12320.876791232153, 12325.260484370967, 12347.444365003596, 12350.876491234972, 12447.004239660806, 340829.0, 341153.0, 345201.0, 345487.0, 345668.0, 51938.74030629823, 52171.21743173855, 52400.0, 52458.47541524536, 52477.475225248025, 1416.3130881522454, 1488.9851101488846, 1490.478332583587, 1495.0954672423245, 1509.433962264151, 21773.84409258237, 22127.557448851254, 22170.0, 22334.664980024987, 22659.0, 37476.25047499089, 37703.62296377111, 37724.81137594295, 37790.811045944596, 37821.43267851039, 303398.0, 303802.0, 304919.0, 305054.0, 305237.0, 29155.125346239645, 29632.259193520335, 29738.405231895675, 32951.67048329486, 33125.67497300178, 37477.0, 37650.0, 41117.1776564461, 41216.38175427431, 42922.35616465693, 27296.590551142144, 28013.0, 28052.43895122127, 28770.712292876804, 29182.0, 28408.147755567366, 29297.85351073231, 29797.255068623457, 29848.701512984593, 29931.0, 35756.64243357533, 35947.28105437824, 36105.0, 36415.725449609134, 36970.260594788495, 23406.531869362178, 26262.86868565645, 28880.133595992153, 30157.69842301549, 31977.0, 24305.0, 24619.384515387257, 24643.507129857662, 24711.50576988486, 24833.751662483144], "value": 17315.236199665393, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.9551520717088, 571.5970240980109, 574.1330590807879, 575.8597976007508, 587.9853003674907, 23554.0, 24118.638220426707, 24349.513009739796, 24389.87805060977, 24441.0, 20816.47958801031, 23195.76804231957, 24995.500269983815, 26977.0, 27078.729212707865, 6561.343865613437, 7069.25772793857, 7078.186008609025, 7088.964555177217, 7092.042574252488, 6180.918339290629, 6935.341142591471, 6957.16514018316, 6996.860062798741, 7019.964900175492, 6342.904856427157, 6632.469402447817, 6742.460603151761, 6760.695768690396, 6962.930370696279, 6756.831079223034, 6843.144606924135, 6843.349881761224, 6844.349786770249, 6849.1780986281465, 5557.194206840026, 6324.146240257553, 6324.2410910691015, 6330.0821380899515, 6347.492200623985, 26285.605715914277, 29655.25861853449, 30791.84604076965, 31555.21111972196, 31990.88031918895, 2249.505108876061, 2283.315005498344, 2294.1052989334007, 2300.4363930836885, 2301.0910690277415, 16575.336986520524, 19430.93129877856, 19731.0, 19819.702704459443, 19843.60312793736, 1895.3555791030979, 2210.7789221077765, 2232.4195709115584, 2277.3054218463362, 2283.0525331987205, 128843.35578322236, 216425.0, 218150.0, 220627.0, 222113.0, 13357.398917048757, 16717.66564668724, 16768.490835824836, 16999.895006824765, 17236.052017138925, 22491.88754056219, 22518.32445026652, 22584.0, 22607.43481412945, 22615.434614134778, 10166.694999150037, 10206.795864082676, 10293.433861137559, 10308.845367319496, 10430.947845260725, 9331.860022099674, 9336.813263734688, 9351.719448416557, 9365.719028429157, 9392.812143757086, 8036.959815200887, 9524.666636667684, 9630.133288004114, 9718.708438746708, 9772.560234789451, 2023.9392818215476, 2331.5803155432186, 2656.3624730064644, 2815.268030312093, 2847.576211894053, 957.9233661307079, 976.4239098931522, 979.4221409368507, 998.7079652198078, 1012.8578057408675, 21991.560168796535, 22162.77837221607, 22179.0, 22241.666375004388, 22975.540489190123, 1010.4089107871977, 1014.5333146752429, 1031.242037102729, 1049.266660349793, 1056.4453661827602, 11105.444727763579, 11342.659720208407, 11461.656150315504, 11464.426778661034, 11988.100892433102, 604356.0, 604769.0, 614907.0, 616567.0, 617712.0, 160659.0, 163667.0, 164401.53397699044, 165123.0, 165916.0, 1151.9673366223508, 1172.3141961952183, 1199.8500187476566, 1209.6612948374293, 1210.1589395370308, 15630.843691563394, 19140.92380111776, 19301.806981930564, 19477.0, 19764.604707906048, 72926.0, 72953.27046729464, 73421.0, 73790.0, 75162.0, 94980.57529137206, 99310.0, 99519.0, 100354.99645003749, 100816.0, 12335.50657973671, 12469.251844888973, 12528.686782830502, 12605.936970315457, 12613.558525451564, 18747.437576872715, 18762.906185468986, 18791.154398052393, 18856.717149242497, 19230.711539326643, 14666.926665366605, 15217.847821522086, 15356.232188390311, 15514.5345639631, 15550.688986220439, 13635.795463067863, 13677.726445471233, 13777.795394189576, 14676.926615366854, 14902.403903843724, 16426.917865411073, 16459.34162633481, 16471.0, 16611.169441527632, 16614.086225257728, 17099.401520946707, 17748.467545973643, 17798.199081041643, 18186.909065454587, 18249.452516424528, 12192.51229950792, 15870.2064896757, 15907.28417221251, 16129.032258064553, 16130.596735081717], "value": 12373.421460375506, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.5373503611327, 1539.738244498435, 1559.1190977097947, 1570.4895908829633, 1594.617291849956, 14979.700405991875, 15576.532704018884, 15637.92181039096, 15640.765388519181, 15653.530394088168, 12933.0, 13272.20366777994, 13360.665983350422, 13386.330683465836, 14900.180490073042, 2424.9272521824373, 2931.2085736851036, 2932.794704370695, 2935.911922642324, 2936.721011503904, 2597.298729343076, 2620.7903367730537, 2621.515019721348, 2624.1602687140025, 2641.815072944895, 2850.3586692994013, 2866.455373479043, 2890.3352228987346, 2890.5086135356933, 2900.7099290071037, 2539.7968162547136, 2554.9233522994246, 2558.0, 2562.693026556451, 2564.4101856572997, 2459.6433517140094, 2813.338865366637, 2827.7879159063154, 2830.938398100712, 2844.416894536615, 14757.0, 14789.852101478955, 14974.47589334379, 14991.925040374837, 14993.250337483083, 7154.213036565973, 8113.188681131898, 8437.156284371575, 8439.367047471464, 8457.069722330538, 4923.138450771136, 5139.948600513984, 5184.688918664892, 5313.920291195635, 5322.121849894763, 1508.2986411318789, 1513.7199618070692, 1518.5140754958418, 1535.58539194418, 1541.899776514523, 22608.321750347517, 23969.520609587886, 24016.15943441971, 24018.879905600534, 24057.39856503584, 7822.335101516321, 7849.0, 7860.253275938777, 7893.565853877977, 8008.27925486709, 18869.245230190914, 18873.0, 18902.149403276886, 18905.62188756217, 18956.620867582573, 8387.0354909185, 8407.201315874981, 8463.407561470638, 8481.194286542892, 8490.108538603481, 6013.909791353133, 7430.702771889172, 7521.849563008818, 7559.1306999695735, 7601.6579253933705, 5648.63283886546, 5843.766249349978, 5969.492593129633, 6032.034874420072, 6070.575059745864, 1848.1867978089867, 1865.3564520240495, 1871.082140805685, 1871.6537440573538, 1889.9527511812314, 788.5978151142958, 826.557791581501, 861.4443683823898, 862.9428949536892, 871.4901782457207, 7184.137903451619, 7304.926950730531, 7345.55926644403, 7584.2036586158765, 7621.0, 692.78177374128, 713.0, 714.1644276196862, 714.7284032067781, 722.5050840174497, 4215.515215750167, 4429.933550996738, 4481.395011673448, 5655.095184770417, 5793.652380857077, 19562.510937226398, 19823.504412389804, 20166.596668066555, 20237.0, 20300.883451410315, 19078.523036924187, 19781.40655780329, 19817.60364792696, 19992.60014799725, 20212.393628190883, 9557.187639050619, 9920.107190352903, 10192.694219173436, 10255.384676919408, 10292.353223484206, 8168.918310816815, 8480.491170529787, 8617.784555385915, 8639.524826134371, 8971.0, 17688.204030818903, 18085.547861303574, 18205.635887281915, 18483.445496635122, 18491.0, 17370.73943890868, 18477.0, 18584.907075464536, 18757.34349297768, 18799.0, 10046.447445390573, 12572.748545029232, 12612.747745044864, 12787.040971926957, 13272.601821945356, 13317.600471985856, 13480.662983425493, 13793.758276904706, 13864.89080873548, 13993.370298336802, 8492.660293588187, 10476.63331783384, 10541.472926353805, 10959.56161753521, 11074.612388566362, 10265.897341026493, 10307.484625768831, 10651.627193048205, 10712.89287107119, 10893.019628233496, 9679.41923484565, 9693.563789629625, 9742.707718768448, 9755.609775608897, 9766.804663906823, 6574.030942446249, 7100.218975912749, 7183.245759195209, 7369.48925470288, 7477.140128885248, 7073.858522829618, 7202.099737532809, 7532.284432978859, 7610.885836712565, 7631.771046868603], "value": 6563.125727368601, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.60871172570904, 471.86551832727656, 503.0944300259532, 506.2760252838442, 511.1489370198617, 5614.915776263354, 6099.451049405553, 6227.47066499347, 6401.871962560746, 6465.612063276207, 5469.781208751655, 6205.937940620593, 6276.089966954789, 6285.7799977000695, 6344.953082741354, 4167.520735115462, 4522.751248681321, 4755.9286610700865, 4778.498257682945, 4787.976060119695, 4456.888577785565, 4643.907121857561, 4789.712617242959, 4805.615550755949, 4812.783424745878, 4762.380890484233, 4764.142454358214, 4765.8570242892765, 4796.026463592578, 4803.853475973243, 4550.5449455054395, 4726.881827954294, 4756.809727610891, 4800.831970881038, 4815.398075240578, 4209.137126888981, 4210.115875666113, 4210.5157906840495, 4219.177260434217, 4236.237477254093, 6170.598911070766, 6190.721417536221, 6224.284207316171, 6230.719617617173, 6270.717817698169, 4998.200287953946, 5664.546836253131, 5718.34239062509, 5736.7952729926765, 5743.339515955677, 5558.44415558445, 6207.951376045894, 6616.974368972817, 6804.76183333581, 6837.965810170967, 4138.820436175675, 4399.812050746281, 4763.166445871993, 4858.295547145644, 4877.317175595419, 6392.456641185505, 7201.279872012757, 7295.7811265661585, 7301.780946571558, 7302.342789148949, 4964.478729733398, 4984.177610694195, 4984.77568509418, 5008.449070602231, 5009.248612708123, 4719.9852031813325, 4788.018456216468, 5047.722375269322, 5082.440931497532, 5090.592752579785, 5297.675581104724, 5482.753276102585, 5730.226419433407, 5768.971155144198, 5795.913061304083, 6239.719212635442, 6816.386525212754, 6817.659117044128, 6879.346462086094, 6939.410150137295, 1449.3767679897744, 1557.470460043568, 1633.4691225351808, 1741.7561541384216, 1742.5120966129505, 1165.5174478361207, 1166.1953252255917, 1168.3165348271186, 1168.8772678868766, 1186.9525218991316, 5422.40353561108, 5460.426655201226, 5969.253843269591, 6252.342754329483, 6277.8744425111245, 5602.635828671123, 6800.52396332252, 6996.8950465743055, 7088.149422069281, 7090.12111790376, 5541.141123125922, 5602.859928501739, 5684.403137670485, 6194.0708893666315, 6421.229452465734, 3372.2918187180967, 3640.8179591020344, 3657.4148136298595, 3689.0592898810633, 3695.279420512974, 6455.7740479083, 6457.128287681103, 7194.496385252982, 7194.604296763624, 7218.386437152901, 7163.42692584592, 7182.138143422718, 7194.856102877913, 7208.125887269348, 7267.0, 6401.9436792928645, 6794.898076528856, 7005.22942476317, 7236.0231368766645, 7302.890456643155, 5457.508824205841, 6418.65208306261, 6942.618156001468, 7038.859222815618, 7066.540674856118, 6107.9083813741945, 6920.688569014507, 6937.549059311129, 6939.375456208763, 6967.965160174167, 6289.339619339903, 6743.35938085881, 7151.4636402269, 7238.27617238293, 7268.491205615556, 3841.3853783394525, 4894.412670479565, 4933.136701077226, 4964.67729597575, 4977.502249774993, 6737.831554211184, 7031.22656507794, 7078.43372530186, 7081.733845169496, 7123.041163679823, 6215.627062376272, 6386.457151142205, 6811.523193376416, 6822.727090916308, 6852.349026842442, 5990.610610310316, 6792.592444453348, 6812.0122582226295, 6835.624040677811, 6847.383735463832, 6602.038776733922, 6661.367170118831, 6669.333066693486, 6685.426110947223, 6691.732330706717, 5865.5600829937075, 6683.866322673616, 6746.662666866735, 6833.180018397626, 6930.0, 6578.868422631616, 6625.503087268377, 6638.502112341497, 6638.701258443285, 6669.4997875160525], "value": 5036.458396547151, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.7701224114404, 837.606325027237, 855.867340562213, 871.8082021955167, 872.4242000279817, 16962.40631577895, 17240.482785516455, 17424.82575174248, 17431.302747890084, 17470.388536401246, 14429.783553246709, 14487.275636218199, 14812.0, 15017.32422041008, 15021.92489037556, 741.0958630470831, 1021.6781713760141, 1021.8416145497459, 1023.9795204095914, 1024.6413755185663, 1061.4957894999852, 1066.8773091094547, 1082.6697857153604, 1082.8954466444222, 1083.1118482844072, 1096.270979798433, 1098.8077086509868, 1115.8772535021142, 1116.966805704724, 1117.3016864459712, 1019.2966852871494, 1046.8115739166947, 1090.7109615951779, 1107.7175320293354, 1109.4785450838094, 799.2407213147475, 800.0479429478902, 803.3573141486787, 804.6017221475348, 809.9149589293098, 18322.26710931561, 18370.540736481562, 18385.816141838543, 18474.630507389913, 18589.62820743591, 16928.49214523566, 17990.830596011216, 18085.819141808544, 18116.094195290316, 18121.36575219861, 14736.115833050051, 14863.777043344358, 15184.544463666107, 15266.847331526653, 15272.770908436383, 3620.619834917322, 3685.244524872395, 3701.000729802938, 3706.6478684525196, 3713.832877520518, 153995.23002385028, 160432.0, 167222.16388918099, 170481.0, 172589.0, 13061.0, 14314.427422903176, 14560.781588276184, 14587.197704126374, 14589.12465252067, 26696.59955100675, 27104.59343109855, 27313.590296145572, 27462.862685686443, 27614.44771104567, 8257.339412846957, 8311.667533298722, 8314.127016663164, 8333.708320208885, 8356.540390278471, 4122.6083522065355, 4141.233871733679, 4180.958190418118, 4181.560936101726, 4188.225178342016, 4897.951020489821, 4962.8759281018265, 4967.5529202371235, 4980.850574482772, 4998.6500944933505, 1059.7085801404676, 1100.625787232345, 1123.1015187849687, 1147.9827802582968, 1148.5176225985106, 634.6953462338104, 666.2604508995012, 672.6569449580749, 696.6621188723465, 696.7909627111948, 11013.66035392734, 11987.22083064598, 12577.93711031439, 13288.867111328957, 14644.12135271887, 634.3624657219557, 648.7664440801308, 698.678607840389, 707.0243064570859, 707.8619669164464, 5547.0, 6085.786997455068, 6180.412860778219, 6418.486521078303, 6551.886179349427, 147905.52094478917, 148311.0, 151196.73204901937, 153405.0, 155943.0, 115386.0, 123636.0, 123731.38134309271, 126344.0, 127755.3612231933, 104359.4782026085, 107838.46080769546, 108720.0, 108806.82386352228, 113096.43451782853, 12540.623781286577, 12702.491900323883, 12854.0, 12981.415836287579, 13083.738325233633, 47418.76290618525, 48238.5176148234, 51759.74120129375, 52704.47295526996, 53466.73266633642, 64756.0, 67629.0, 68675.31324686889, 79947.20052799398, 94413.0, 15311.234438278265, 17769.555761106076, 17803.0, 17924.283028678707, 18614.51083913311, 18235.908820455814, 19292.614147716686, 19814.108365123317, 20155.0, 20410.59178816445, 18268.36060737868, 18275.725864111784, 18734.063296835375, 18768.7184692227, 18918.337858175448, 17285.049322287396, 18116.094195290447, 18275.0, 18868.05659717036, 18969.810301896803, 17739.556511087325, 17832.19755111049, 18252.26990920348, 18358.0, 18428.89426634406, 12335.444904979477, 12711.42798574085, 12841.614751557468, 13083.41124649374, 13143.408546615234, 11882.762344752884, 13516.0, 14746.336414861571, 14985.550433487013, 15137.470188543348], "value": 9266.56874611899, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1565.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 114.0, 113.0, 120.0, 116.0, 113.0, 118.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 113.0, 150.0, 141.0, 123.0, 129.0, 108.0, 147.0, 146.0, 152.0], "value": 134.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 204.0, 199.0, 201.0, 209.0, 226.0, 230.0, 206.0, 202.0, 212.0], "value": 209.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 86.0, 102.0, 188.0, 80.0, 84.0, 57.0, 62.0, 127.0, 58.0], "value": 91.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 113.0, 68.0, 80.0, 80.0, 113.0, 73.0, 73.0, 115.0], "value": 85.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 92.0, 93.0, 92.0, 106.0, 130.0, 84.0, 131.0, 132.0], "value": 104.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 98.0, 98.0, 97.0, 99.0, 96.0, 97.0, 107.0, 97.0, 98.0], "value": 98.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 96.0, 148.0, 145.0, 93.0, 90.0, 92.0, 76.0, 143.0, 71.0], "value": 108.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 53.0, 50.0, 53.0, 54.0, 51.0, 51.0, 53.0, 54.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 76.0, 52.0, 48.0, 81.0, 47.0, 56.0, 60.0, 76.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 81.0, 87.0, 102.0, 108.0, 70.0, 107.0, 103.0, 103.0], "value": 92.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 146.0, 135.0, 135.0, 134.0, 164.0, 140.0, 140.0, 140.0, 169.0], "value": 143.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [218.0, 210.0, 178.0, 185.0, 207.0, 230.0, 205.0, 157.0, 196.0, 166.0], "value": 195.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 95.0, 83.0, 67.0, 62.0, 67.0, 66.0, 80.0, 77.0, 90.0], "value": 75.8, "unit": "score"}]}]} 12:29:14 INFO - Return code: 0 12:29:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:29:14 INFO - # TBPL SUCCESS # 12:29:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:29:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:29:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:29:14 INFO - 2017-06-21 12:29:14.721 screenresolution[2263:28673] starting screenresolution argv=screenresolution get 12:29:14 INFO - 2017-06-21 12:29:14.724 screenresolution[2263:28673] Display 0: 1600x1200x32@60 12:29:14 INFO - 2017-06-21 12:29:14.730 screenresolution[2264:28676] starting screenresolution argv=screenresolution list 12:29:14 INFO - Available Modes on Display 0 12:29:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:29:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:29:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:29:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:29:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:29:14 INFO - Intel Iris: 12:29:14 INFO - Chipset Model: Intel Iris 12:29:14 INFO - Type: GPU 12:29:14 INFO - Bus: Built-In 12:29:14 INFO - VRAM (Dynamic, Max): 1536 MB 12:29:14 INFO - Vendor: Intel (0x8086) 12:29:14 INFO - Device ID: 0x0a2e 12:29:14 INFO - Revision ID: 0x0009 12:29:14 INFO - Displays: 12:29:14 INFO - Display: 12:29:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:29:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:29:14 INFO - Display Serial Number: 1600x1200 60 12:29:14 INFO - Main Display: Yes 12:29:14 INFO - Mirror: Off 12:29:14 INFO - Online: Yes 12:29:14 INFO - Rotation: Supported 12:29:14 INFO - Return code: 0 12:29:14 INFO - Running post-action listener: _resource_record_post_action 12:29:14 INFO - [mozharness: 2017-06-21 19:29:14.974820Z] Finished run-tests step (success) 12:29:14 INFO - Running post-run listener: _resource_record_post_run 12:29:15 INFO - Total resource usage - Wall time: 1205s; CPU: 55.0%; Read bytes: 512; Write bytes: 570093568; Read time: 0; Write time: 1778 12:29:15 INFO - TinderboxPrint: CPU usage
54.6% 12:29:15 INFO - TinderboxPrint: I/O read bytes / time
512 / 0 12:29:15 INFO - TinderboxPrint: I/O write bytes / time
570,093,568 / 1,778 12:29:15 INFO - TinderboxPrint: CPU idle
2,190.2 (45.4%) 12:29:15 INFO - TinderboxPrint: CPU system
983.5 (20.4%) 12:29:15 INFO - TinderboxPrint: CPU user
1,648.0 (34.2%) 12:29:15 INFO - TinderboxPrint: Swap in / out
253,460,480 / 0 12:29:15 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 237908480; Write bytes: 236797952; Read time: 17887; Write time: 431 12:29:15 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:29:15 INFO - run-tests - Wall time: 1183s; CPU: 55.0%; Read bytes: 0; Write bytes: 312817664; Read time: 0; Write time: 1250 12:29:15 INFO - Running post-run listener: _upload_blobber_files 12:29:15 INFO - Blob upload gear active. 12:29:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:29:15 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:29:15 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:29:15 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:29:15 INFO - (blobuploader) - INFO - Open directory for files ... 12:29:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:29:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:29:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:17 INFO - (blobuploader) - INFO - Done attempting. 12:29:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:29:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:29:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:17 INFO - (blobuploader) - INFO - Done attempting. 12:29:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:29:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:29:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:18 INFO - (blobuploader) - INFO - Done attempting. 12:29:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:29:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:29:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:21 INFO - (blobuploader) - INFO - Done attempting. 12:29:21 INFO - (blobuploader) - INFO - Iteration through files over. 12:29:21 INFO - Return code: 0 12:29:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:29:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:29:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5a883e430bd00649b6a941b72ab808ac032b2c6d0562cf7cd883df5fa59903c27d642031670fd747c20338d7766edf36e6252752b0254432a372e5a005e47048", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2f752e15d426ff527464da061a8d3768eefccf27524113f1c72b9e5c39a71c8c465f9e952b1f1e8e05a12686890cbe8721c0b99a492917be64dd5aa1c9102ef7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/07284d6e626d18b2cd3291b8e5069a365020b697f1c7bda6632298398dccf0680dc91d46cfb5f04856db23fcf98a9e9a5858340d110ad83d22f7f448d8034d94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c72f38dc99d91dc85924a1ee715a7549989232139e13115830852764572d9bd0338579220cab79395ef0c8c69973b31d8e89360f0723839e0467c5d935117566"} 12:29:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:29:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:29:21 INFO - Contents: 12:29:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5a883e430bd00649b6a941b72ab808ac032b2c6d0562cf7cd883df5fa59903c27d642031670fd747c20338d7766edf36e6252752b0254432a372e5a005e47048", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2f752e15d426ff527464da061a8d3768eefccf27524113f1c72b9e5c39a71c8c465f9e952b1f1e8e05a12686890cbe8721c0b99a492917be64dd5aa1c9102ef7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/07284d6e626d18b2cd3291b8e5069a365020b697f1c7bda6632298398dccf0680dc91d46cfb5f04856db23fcf98a9e9a5858340d110ad83d22f7f448d8034d94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c72f38dc99d91dc85924a1ee715a7549989232139e13115830852764572d9bd0338579220cab79395ef0c8c69973b31d8e89360f0723839e0467c5d935117566"} 12:29:21 INFO - Running post-run listener: copy_logs_to_upload_dir 12:29:21 INFO - Copying logs to upload dir... 12:29:21 INFO - mkdir: /builds/slave/test/build/upload/logs 12:29:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.523865 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-06-21 12:29:21.272662) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-21 12:29:21.276199) ========= 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.Bczb4Fvcuo/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.Rc8EiEi9PS/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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/Pine/sha512/5a883e430bd00649b6a941b72ab808ac032b2c6d0562cf7cd883df5fa59903c27d642031670fd747c20338d7766edf36e6252752b0254432a372e5a005e47048", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2f752e15d426ff527464da061a8d3768eefccf27524113f1c72b9e5c39a71c8c465f9e952b1f1e8e05a12686890cbe8721c0b99a492917be64dd5aa1c9102ef7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/07284d6e626d18b2cd3291b8e5069a365020b697f1c7bda6632298398dccf0680dc91d46cfb5f04856db23fcf98a9e9a5858340d110ad83d22f7f448d8034d94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c72f38dc99d91dc85924a1ee715a7549989232139e13115830852764572d9bd0338579220cab79395ef0c8c69973b31d8e89360f0723839e0467c5d935117566"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011215 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5a883e430bd00649b6a941b72ab808ac032b2c6d0562cf7cd883df5fa59903c27d642031670fd747c20338d7766edf36e6252752b0254432a372e5a005e47048", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2f752e15d426ff527464da061a8d3768eefccf27524113f1c72b9e5c39a71c8c465f9e952b1f1e8e05a12686890cbe8721c0b99a492917be64dd5aa1c9102ef7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/07284d6e626d18b2cd3291b8e5069a365020b697f1c7bda6632298398dccf0680dc91d46cfb5f04856db23fcf98a9e9a5858340d110ad83d22f7f448d8034d94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c72f38dc99d91dc85924a1ee715a7549989232139e13115830852764572d9bd0338579220cab79395ef0c8c69973b31d8e89360f0723839e0467c5d935117566"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-21 12:29:21.309083) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:29:21.309418) ========= 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.Bczb4Fvcuo/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.Rc8EiEi9PS/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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.005257 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:29:21.357655) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-21 12:29:21.358006) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-21 12:29:21.358287) ========= ========= Total master_lag: 0.18 =========