builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0381 starttime: 1498071862.44 results: success (0) buildid: 20170621111549 builduid: 2ad414d771ef407f8852b9add51654b4 revision: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-21 12:04:22.439972) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-21 12:04:22.440434) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-21 12:04:22.440724) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-21 12:04:22.463720) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:04:22.464068) ========= 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.gEYUtR9xDo/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.q1jzIBRHRX/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:04:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 155M=0s 2017-06-21 12:04:22 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138901 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:04:22.624195) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:04:22.624543) ========= 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.gEYUtR9xDo/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.q1jzIBRHRX/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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.021460 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:04:22.673998) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-21 12:04:22.674712) ========= 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.gEYUtR9xDo/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.q1jzIBRHRX/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:04:22,760 truncating revision to first 12 chars 2017-06-21 12:04:22,761 Setting DEBUG logging. 2017-06-21 12:04:22,761 attempt 1/10 2017-06-21 12:04:22,761 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:04:24,415 unpacking tar archive at: pine-4f227bec7585/testing/mozharness/ program finished with exit code 0 elapsedTime=1.916921 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-21 12:04:24.613346) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-21 12:04:24.613657) ========= script_repo_revision: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-21 12:04:24.614029) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-21 12:04:24.614289) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-21 12:04:24.631327) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-06-21 12:04:24.631608) ========= /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.gEYUtR9xDo/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.q1jzIBRHRX/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:04:24 INFO - MultiFileLogger online at 20170621 12:04:24 in /builds/slave/test 12:04:24 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:04:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:04:24 INFO - {'append_to_log': False, 12:04:24 INFO - 'base_work_dir': '/builds/slave/test', 12:04:24 INFO - 'blob_upload_branch': 'Pine', 12:04:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:04:24 INFO - 'branch': 'Pine', 12:04:24 INFO - 'buildbot_json_path': 'buildprops.json', 12:04:24 INFO - 'config_files': ('talos/mac_config.py',), 12:04:24 INFO - 'default_actions': ('clobber', 12:04:24 INFO - 'read-buildbot-config', 12:04:24 INFO - 'download-and-extract', 12:04:24 INFO - 'populate-webroot', 12:04:24 INFO - 'create-virtualenv', 12:04:24 INFO - 'install', 12:04:24 INFO - 'setup-mitmproxy', 12:04:24 INFO - 'run-tests'), 12:04:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:04:24 INFO - 'download_minidump_stackwalk': True, 12:04:24 INFO - 'download_symbols': 'ondemand', 12:04:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:04:24 INFO - 'tooltool.py': '/tools/tooltool.py', 12:04:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:04:24 INFO - '/tools/misc-python/virtualenv.py')}, 12:04:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:04:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:04:24 INFO - 'gecko_profile': False, 12:04:24 INFO - 'gecko_profile_interval': 0, 12:04:24 INFO - 'installer_path': 'installer.exe', 12:04:24 INFO - 'log_level': 'info', 12:04:24 INFO - 'log_name': 'talos', 12:04:24 INFO - 'log_to_console': True, 12:04:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:04:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:04:24 INFO - 'opt_config_files': (), 12:04:24 INFO - 'pip_index': False, 12:04:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:04:24 INFO - 'cmd': ('bash', 12:04:24 INFO - '-c', 12:04:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:04:24 INFO - 'enabled': True, 12:04:24 INFO - 'halt_on_failure': False, 12:04:24 INFO - 'name': 'check_screen_resolution'},), 12:04:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:04:24 INFO - 'cmd': ('bash', 12:04:24 INFO - '-c', 12:04:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:04:24 INFO - 'enabled': True, 12:04:24 INFO - 'halt_on_failure': False, 12:04:24 INFO - 'name': 'check_screen_resolution'},), 12:04:24 INFO - 'run_cmd_checks_enabled': True, 12:04:24 INFO - 'suite': 'dromaeojs-e10s', 12:04:24 INFO - 'system_bits': '32', 12:04:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:04:24 INFO - 'title': 't-yosemite-r7-0381', 12:04:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:04:24 INFO - 'use_talos_json': True, 12:04:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:04:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:04:24 INFO - 'work_dir': 'build'} 12:04:24 INFO - [mozharness: 2017-06-21 19:04:24.836326Z] Running clobber step. 12:04:24 INFO - Running pre-action listener: _resource_record_pre_action 12:04:24 INFO - Running main action method: clobber 12:04:24 INFO - rmtree: /builds/slave/test/build 12:04:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:04:27 INFO - Running post-action listener: _resource_record_post_action 12:04:27 INFO - [mozharness: 2017-06-21 19:04:27.246405Z] Finished clobber step (success) 12:04:27 INFO - [mozharness: 2017-06-21 19:04:27.246585Z] Running read-buildbot-config step. 12:04:27 INFO - Running pre-action listener: _resource_record_pre_action 12:04:27 INFO - Running main action method: read_buildbot_config 12:04:27 INFO - Using buildbot properties: 12:04:27 INFO - { 12:04:27 INFO - "project": "", 12:04:27 INFO - "product": "firefox", 12:04:27 INFO - "script_repo_revision": "default", 12:04:27 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 12:04:27 INFO - "repository": "", 12:04:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 12:04:27 INFO - "buildid": "20170621111549", 12:04:27 INFO - "stage_platform": "macosx64", 12:04:27 INFO - "basedir": "/builds/slave/test", 12:04:27 INFO - "buildnumber": 0, 12:04:27 INFO - "platform": "yosemite_r7", 12:04:27 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:04:27 INFO - "slavebuilddir": "test", 12:04:27 INFO - "branch": "pine", 12:04:27 INFO - "revision": "4f227bec7585a7e4487d7da44c5cc7edd2ad5654", 12:04:27 INFO - "slavename": "t-yosemite-r7-0381", 12:04:27 INFO - "builduid": "2ad414d771ef407f8852b9add51654b4", 12:04:27 INFO - "repo_path": "projects/pine" 12:04:27 INFO - } 12:04:27 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg. 12:04:27 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:04:27 INFO - Running post-action listener: _resource_record_post_action 12:04:27 INFO - [mozharness: 2017-06-21 19:04:27.251808Z] Finished read-buildbot-config step (success) 12:04:27 INFO - [mozharness: 2017-06-21 19:04:27.252040Z] Running download-and-extract step. 12:04:27 INFO - Running pre-action listener: _resource_record_pre_action 12:04:27 INFO - Running main action method: download_and_extract 12:04:27 INFO - mkdir: /builds/slave/test/build/tests 12:04:27 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:04:27 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.test_packages.json 12:04:27 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:04:27 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:04:27 INFO - Downloaded 1328 bytes. 12:04:27 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 12:04:27 INFO - Using the following test package requirements: 12:04:27 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:04:27 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 12:04:27 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 12:04:27 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:04:27 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 12:04:27 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:04:27 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 12:04:27 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:04:27 INFO - u'jsshell-mac.zip'], 12:04:27 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:04:27 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 12:04:27 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 12:04:27 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:04:27 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 12:04:27 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:04:27 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 12:04:27 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:04:27 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 12:04:27 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:04:27 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 12:04:27 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:04:27 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:04:27 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:04:27 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:04:28 INFO - Content-Length response header: 59596040 12:04:28 INFO - Bytes received: 59596040 12:04:46 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:04:46 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:04:46 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:04:46 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:04:48 INFO - Content-Length response header: 59596040 12:04:48 INFO - Bytes received: 59596040 12:05:05 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:05:05 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:05:05 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:05:05 INFO - Content-Length response header: 14457085 12:05:05 INFO - Bytes received: 14457085 12:05: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:05:06 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg 12:05:06 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:05:06 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:05:07 INFO - Downloaded 69703961 bytes. 12:05:07 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:05:07 INFO - mkdir: /builds/slave/test/properties 12:05:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:05:07 INFO - Writing to file /builds/slave/test/properties/build_url 12:05:07 INFO - Contents: 12:05:07 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg 12:05:07 INFO - Running post-action listener: _resource_record_post_action 12:05:07 INFO - Running post-action listener: set_extra_try_arguments 12:05:07 INFO - [mozharness: 2017-06-21 19:05:07.620391Z] Finished download-and-extract step (success) 12:05:07 INFO - [mozharness: 2017-06-21 19:05:07.620524Z] Running populate-webroot step. 12:05:07 INFO - Running pre-action listener: _resource_record_pre_action 12:05:07 INFO - Running main action method: populate_webroot 12:05:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:05:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:05:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:05:07 INFO - '--tppagecycles', 12:05:07 INFO - '7'], 12:05:07 INFO - 'tests': ['tsvgm']}}, 12:05:07 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:05:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:05:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:05:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:05:07 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:05:07 INFO - 'talos_options': ['--disable-e10s'], 12:05:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:05:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:05:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:05:07 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:05:07 INFO - 'talos_options': ['--disable-e10s'], 12:05:07 INFO - 'tests': ['damp', 'tps']}, 12:05:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:05:07 INFO - 'tests': ['damp', 'tps']}, 12:05:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:05:07 INFO - 'tests': ['dromaeo_dom']}, 12:05:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:05:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:05:07 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:05:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:05:07 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:05:07 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:05:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:05:07 INFO - 'tests': ['a11yr', 12:05:07 INFO - 'ts_paint', 12:05:07 INFO - 'tpaint', 12:05:07 INFO - 'sessionrestore', 12:05:07 INFO - 'sessionrestore_no_auto_restore', 12:05:07 INFO - 'tabpaint']}, 12:05:07 INFO - 'other-e10s': {'tests': ['a11yr', 12:05:07 INFO - 'ts_paint', 12:05:07 INFO - 'tpaint', 12:05:07 INFO - 'sessionrestore', 12:05:07 INFO - 'sessionrestore_no_auto_restore', 12:05:07 INFO - 'tabpaint']}, 12:05:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:05:07 INFO - 'ts_paint', 12:05:07 INFO - 'tpaint', 12:05:07 INFO - 'sessionrestore', 12:05:07 INFO - 'sessionrestore_no_auto_restore', 12:05:07 INFO - 'tabpaint']}, 12:05:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:05:07 INFO - 'ts_paint', 12:05:07 INFO - 'tpaint', 12:05:07 INFO - 'sessionrestore', 12:05:07 INFO - 'sessionrestore_no_auto_restore', 12:05:07 INFO - 'tabpaint']}, 12:05:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:05:07 INFO - 'tests': ['a11yr', 12:05:07 INFO - 'ts_paint', 12:05:07 INFO - 'tpaint', 12:05:07 INFO - 'sessionrestore', 12:05:07 INFO - 'sessionrestore_no_auto_restore', 12:05:07 INFO - 'tabpaint']}, 12:05:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:05:07 INFO - 'tests': ['a11yr', 12:05:07 INFO - 'ts_paint', 12:05:07 INFO - 'tpaint', 12:05:07 INFO - 'sessionrestore', 12:05:07 INFO - 'sessionrestore_no_auto_restore', 12:05:07 INFO - 'tabpaint']}, 12:05:07 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:05:07 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:05:07 INFO - 'bloom_basic_ref']}, 12:05:07 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:05:07 INFO - 'talos_options': ['--mitmproxy', 12:05:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:05:07 INFO - '--firstNonBlankPaint'], 12:05:07 INFO - 'tests': ['quantum_pageload_google', 12:05:07 INFO - 'quantum_pageload_youtube', 12:05:07 INFO - 'quantum_pageload_amazon', 12:05:07 INFO - 'quantum_pageload_facebook']}, 12:05:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:05:07 INFO - 'tests': ['tsvgx', 12:05:07 INFO - 'tsvgr_opacity', 12:05:07 INFO - 'tart', 12:05:07 INFO - 'tscrollx', 12:05:07 INFO - 'cart', 12:05:07 INFO - 'tsvg_static']}, 12:05:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:05:07 INFO - 'tsvgr_opacity', 12:05:07 INFO - 'tart', 12:05:07 INFO - 'tscrollx', 12:05:07 INFO - 'cart', 12:05:07 INFO - 'tsvg_static']}, 12:05:07 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:05:07 INFO - 'talos_options': ['--disable-e10s'], 12:05:07 INFO - 'tests': ['tp5o']}, 12:05:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:05:07 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:05:07 INFO - 'talos_options': ['--disable-e10s', 12:05:07 INFO - '--xperf_path', 12:05:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:05:07 INFO - 'tests': ['tp5n']}, 12:05:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:05:07 INFO - 'talos_options': ['--xperf_path', 12:05:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:05:07 INFO - 'tests': ['tp5n']}}, 12:05:07 INFO - 'talos.zip': {'path': '', 12:05:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:05:07 INFO - Running post-action listener: _resource_record_post_action 12:05:07 INFO - [mozharness: 2017-06-21 19:05:07.642401Z] Finished populate-webroot step (success) 12:05:07 INFO - [mozharness: 2017-06-21 19:05:07.642528Z] Running create-virtualenv step. 12:05:07 INFO - Running pre-action listener: _resource_record_pre_action 12:05:07 INFO - Running main action method: create_virtualenv 12:05:07 INFO - Creating virtualenv /builds/slave/test/build/venv 12:05:07 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:05:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:05:07 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:05:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:05:07 INFO - Using real prefix '/tools/python27' 12:05:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:05:08 INFO - Installing distribute.............................................................................................................................................................................................done. 12:05:11 INFO - Installing pip.................done. 12:05:11 INFO - Return code: 0 12:05:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:05:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:05:11 INFO - Reading from file tmpfile_stdout 12:05:11 INFO - Output received: 12:05:11 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:05:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bef6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10befa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe024b7460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc4cd30>, '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 0x10bc16cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc3dbe8>, '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.gEYUtR9xDo/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.q1jzIBRHRX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:05:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:05:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:05:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gEYUtR9xDo/Render', 12:05:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:11 INFO - 'HOME': '/Users/cltbld', 12:05:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:11 INFO - 'LOGNAME': 'cltbld', 12:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:11 INFO - 'MOZ_NO_REMOTE': '1', 12:05:11 INFO - 'NO_EM_RESTART': '1', 12:05:11 INFO - 'PAGER': '/bin/cat', 12:05:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:11 INFO - 'PWD': '/builds/slave/test', 12:05:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:11 INFO - 'SHELL': '/bin/bash', 12:05:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1jzIBRHRX/Listeners', 12:05:11 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 12:05:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:11 INFO - 'USER': 'cltbld', 12:05:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:11 INFO - 'XPC_FLAGS': '0x0', 12:05:11 INFO - 'XPC_SERVICE_NAME': '0', 12:05:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:11 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:05:11 INFO - Cleaning up... 12:05:11 INFO - Return code: 0 12:05:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bef6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10befa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe024b7460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc4cd30>, '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 0x10bc16cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc3dbe8>, '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.gEYUtR9xDo/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.q1jzIBRHRX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:05:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:05:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:05:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gEYUtR9xDo/Render', 12:05:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:11 INFO - 'HOME': '/Users/cltbld', 12:05:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:11 INFO - 'LOGNAME': 'cltbld', 12:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:11 INFO - 'MOZ_NO_REMOTE': '1', 12:05:11 INFO - 'NO_EM_RESTART': '1', 12:05:11 INFO - 'PAGER': '/bin/cat', 12:05:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:11 INFO - 'PWD': '/builds/slave/test', 12:05:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:11 INFO - 'SHELL': '/bin/bash', 12:05:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1jzIBRHRX/Listeners', 12:05:11 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 12:05:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:11 INFO - 'USER': 'cltbld', 12:05:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:11 INFO - 'XPC_FLAGS': '0x0', 12:05:11 INFO - 'XPC_SERVICE_NAME': '0', 12:05:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:12 INFO - Downloading/unpacking psutil>=3.1.1 12:05:12 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:05:12 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:05:12 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:05:12 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:05:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:05:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:05:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:05:14 INFO - Installing collected packages: psutil 12:05:14 INFO - Running setup.py install for psutil 12:05:14 INFO - building 'psutil._psutil_osx' extension 12:05:14 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:05:14 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:05:14 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:05:14 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:05:14 INFO - building 'psutil._psutil_posix' extension 12:05:14 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:05:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:05:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:05:14 INFO - ^ 12:05:14 INFO - 1 warning generated. 12:05:14 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:05:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:05:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:05:14 INFO - Successfully installed psutil 12:05:14 INFO - Cleaning up... 12:05:14 INFO - Return code: 0 12:05:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:05:14 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:05:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:14 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:05:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bef6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10befa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe024b7460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc4cd30>, '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 0x10bc16cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc3dbe8>, '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.gEYUtR9xDo/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.q1jzIBRHRX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:05:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:05:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:05:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gEYUtR9xDo/Render', 12:05:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:14 INFO - 'HOME': '/Users/cltbld', 12:05:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:14 INFO - 'LOGNAME': 'cltbld', 12:05:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:14 INFO - 'MOZ_NO_REMOTE': '1', 12:05:14 INFO - 'NO_EM_RESTART': '1', 12:05:14 INFO - 'PAGER': '/bin/cat', 12:05:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:14 INFO - 'PWD': '/builds/slave/test', 12:05:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:14 INFO - 'SHELL': '/bin/bash', 12:05:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1jzIBRHRX/Listeners', 12:05:14 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 12:05:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:14 INFO - 'USER': 'cltbld', 12:05:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:14 INFO - 'XPC_FLAGS': '0x0', 12:05:14 INFO - 'XPC_SERVICE_NAME': '0', 12:05:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:05:15 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:05:15 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:05:15 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:05:15 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:05:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:05:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:05:16 INFO - Installing collected packages: mozsystemmonitor 12:05:16 INFO - Running setup.py install for mozsystemmonitor 12:05:16 INFO - Successfully installed mozsystemmonitor 12:05:16 INFO - Cleaning up... 12:05:16 INFO - Return code: 0 12:05:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bef6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10befa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe024b7460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc4cd30>, '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 0x10bc16cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc3dbe8>, '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.gEYUtR9xDo/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.q1jzIBRHRX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:05:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:05:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gEYUtR9xDo/Render', 12:05:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:16 INFO - 'HOME': '/Users/cltbld', 12:05:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:16 INFO - 'LOGNAME': 'cltbld', 12:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:16 INFO - 'MOZ_NO_REMOTE': '1', 12:05:16 INFO - 'NO_EM_RESTART': '1', 12:05:16 INFO - 'PAGER': '/bin/cat', 12:05:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:16 INFO - 'PWD': '/builds/slave/test', 12:05:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:16 INFO - 'SHELL': '/bin/bash', 12:05:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1jzIBRHRX/Listeners', 12:05:16 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 12:05:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:16 INFO - 'USER': 'cltbld', 12:05:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:16 INFO - 'XPC_FLAGS': '0x0', 12:05:16 INFO - 'XPC_SERVICE_NAME': '0', 12:05:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:17 INFO - Downloading/unpacking jsonschema==2.5.1 12:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:05:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:18 INFO - Downloading functools32-3.2.3-2.tar.gz 12:05:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:05:18 INFO - warning: no files found matching '*.txt' 12:05:18 INFO - no previously-included directories found matching 'build' 12:05:18 INFO - no previously-included directories found matching 'dist' 12:05:18 INFO - no previously-included directories found matching '.git*' 12:05:18 INFO - Installing collected packages: jsonschema, functools32 12:05:18 INFO - Running setup.py install for functools32 12:05:18 INFO - warning: no files found matching '*.txt' 12:05:18 INFO - no previously-included directories found matching 'build' 12:05:18 INFO - no previously-included directories found matching 'dist' 12:05:18 INFO - no previously-included directories found matching '.git*' 12:05:19 INFO - Successfully installed jsonschema functools32 12:05:19 INFO - Cleaning up... 12:05:19 INFO - Return code: 0 12:05:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bef6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10befa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe024b7460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc4cd30>, '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 0x10bc16cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc3dbe8>, '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.gEYUtR9xDo/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.q1jzIBRHRX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:05:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:05:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:05:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gEYUtR9xDo/Render', 12:05:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:20 INFO - 'HOME': '/Users/cltbld', 12:05:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:20 INFO - 'LOGNAME': 'cltbld', 12:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:20 INFO - 'MOZ_NO_REMOTE': '1', 12:05:20 INFO - 'NO_EM_RESTART': '1', 12:05:20 INFO - 'PAGER': '/bin/cat', 12:05:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:20 INFO - 'PWD': '/builds/slave/test', 12:05:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:20 INFO - 'SHELL': '/bin/bash', 12:05:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1jzIBRHRX/Listeners', 12:05:20 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 12:05:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:20 INFO - 'USER': 'cltbld', 12:05:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:20 INFO - 'XPC_FLAGS': '0x0', 12:05:20 INFO - 'XPC_SERVICE_NAME': '0', 12:05:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:05:20 INFO - Cleaning up... 12:05:20 INFO - Return code: 0 12:05:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:05:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bef6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10befa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe024b7460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc4cd30>, '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 0x10bc16cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc3dbe8>, '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.gEYUtR9xDo/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.q1jzIBRHRX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:05:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:05:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:05:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gEYUtR9xDo/Render', 12:05:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:20 INFO - 'HOME': '/Users/cltbld', 12:05:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:20 INFO - 'LOGNAME': 'cltbld', 12:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:20 INFO - 'MOZ_NO_REMOTE': '1', 12:05:20 INFO - 'NO_EM_RESTART': '1', 12:05:20 INFO - 'PAGER': '/bin/cat', 12:05:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:20 INFO - 'PWD': '/builds/slave/test', 12:05:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:20 INFO - 'SHELL': '/bin/bash', 12:05:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1jzIBRHRX/Listeners', 12:05:20 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 12:05:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:20 INFO - 'USER': 'cltbld', 12:05:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:20 INFO - 'XPC_FLAGS': '0x0', 12:05:20 INFO - 'XPC_SERVICE_NAME': '0', 12:05:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:21 INFO - Downloading/unpacking blobuploader==1.2.4 12:05:21 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:05:21 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:05:21 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:05:21 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:05:22 INFO - Downloading blobuploader-1.2.4.tar.gz 12:05:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:05:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:05:22 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:05:22 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:05:22 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:05:22 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:05:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:05:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:05:23 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:05:23 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:05:23 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:05:23 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:05:23 INFO - Downloading docopt-0.6.1.tar.gz 12:05:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:05:23 INFO - Installing collected packages: blobuploader, requests, docopt 12:05:23 INFO - Running setup.py install for blobuploader 12:05:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:05:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:05:23 INFO - Running setup.py install for requests 12:05:24 INFO - Running setup.py install for docopt 12:05:24 INFO - Successfully installed blobuploader requests docopt 12:05:24 INFO - Cleaning up... 12:05:24 INFO - Return code: 0 12:05:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:05:24 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:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:24 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:05:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:24 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 0x10bef6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10befa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe024b7460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc4cd30>, '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 0x10bc16cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc3dbe8>, '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.gEYUtR9xDo/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.q1jzIBRHRX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:05:24 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:05:24 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:05:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gEYUtR9xDo/Render', 12:05:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:24 INFO - 'HOME': '/Users/cltbld', 12:05:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:24 INFO - 'LOGNAME': 'cltbld', 12:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:24 INFO - 'MOZ_NO_REMOTE': '1', 12:05:24 INFO - 'NO_EM_RESTART': '1', 12:05:24 INFO - 'PAGER': '/bin/cat', 12:05:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:24 INFO - 'PWD': '/builds/slave/test', 12:05:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:24 INFO - 'SHELL': '/bin/bash', 12:05:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1jzIBRHRX/Listeners', 12:05:24 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 12:05:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:24 INFO - 'USER': 'cltbld', 12:05:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:24 INFO - 'XPC_FLAGS': '0x0', 12:05:24 INFO - 'XPC_SERVICE_NAME': '0', 12:05:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:05:24 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-oNjIvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:05:24 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-TjI9zk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:05:25 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-x2HIlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:05:25 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-d_SWiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:05:25 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-zsw_hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:05:25 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-oO9F_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:05:25 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-LShFEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:05:25 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-nz4u73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:05:25 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-ZKjaeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:05:25 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-uthXt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:05:25 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-Rfj21Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:05:25 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-VxAT49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:05:26 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-iW2nH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:05:26 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-xuynkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:26 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-RbwU90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:05:26 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-ce2Ze6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:05:26 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-1axteb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:05:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:05:26 INFO - Running setup.py install for manifestparser 12:05:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:05:26 INFO - Running setup.py install for mozcrash 12:05:26 INFO - Running setup.py install for mozdebug 12:05:26 INFO - Running setup.py install for mozdevice 12:05:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:05:27 INFO - Running setup.py install for mozfile 12:05:27 INFO - Running setup.py install for mozhttpd 12:05:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:05:27 INFO - Running setup.py install for mozinfo 12:05:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:05:27 INFO - Running setup.py install for mozInstall 12:05:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:05:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:05:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:05:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:05:27 INFO - Running setup.py install for mozleak 12:05:27 INFO - Running setup.py install for mozlog 12:05:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:05:28 INFO - Running setup.py install for moznetwork 12:05:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:05:28 INFO - Running setup.py install for mozprocess 12:05:28 INFO - Running setup.py install for mozprofile 12:05:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:05:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:05:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:05:28 INFO - Running setup.py install for mozrunner 12:05:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:05:28 INFO - Running setup.py install for mozscreenshot 12:05:28 INFO - Running setup.py install for moztest 12:05:28 INFO - Running setup.py install for mozversion 12:05:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:05:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:05:29 INFO - Cleaning up... 12:05:29 INFO - Return code: 0 12:05:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:29 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 0x10bef6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10befa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe024b7460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc4cd30>, '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 0x10bc16cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc3dbe8>, '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.gEYUtR9xDo/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.q1jzIBRHRX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:05:29 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:05:29 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:05:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gEYUtR9xDo/Render', 12:05:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:29 INFO - 'HOME': '/Users/cltbld', 12:05:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:29 INFO - 'LOGNAME': 'cltbld', 12:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:29 INFO - 'MOZ_NO_REMOTE': '1', 12:05:29 INFO - 'NO_EM_RESTART': '1', 12:05:29 INFO - 'PAGER': '/bin/cat', 12:05:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:29 INFO - 'PWD': '/builds/slave/test', 12:05:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:29 INFO - 'SHELL': '/bin/bash', 12:05:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1jzIBRHRX/Listeners', 12:05:29 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 12:05:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:29 INFO - 'USER': 'cltbld', 12:05:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:29 INFO - 'XPC_FLAGS': '0x0', 12:05:29 INFO - 'XPC_SERVICE_NAME': '0', 12:05:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:05:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-ZKkV9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:05:29 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:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:05:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-WqfD3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:05:29 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:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:05:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-5F3Cuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:05:29 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:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:05:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-Rc6yY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:05:29 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:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:05:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-Yi_76E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:05:29 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:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:05:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-upOeuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:05:30 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:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:05:30 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-6uRGnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:05:30 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:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:05:30 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-NZ_Xow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:05:30 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:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:05:30 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-O4kA5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:05:30 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:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:05:30 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-Rwvlcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:05:30 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:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:05:30 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-326Imp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:05:30 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:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:05:30 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-K55MIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:05:30 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:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:05:30 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-fbgH4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:05:30 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:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:05:30 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-RR7MOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:05:30 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:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:30 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-ZsrInl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:31 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:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:05:31 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-7uKa3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:05:31 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:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:05:31 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-NSjmD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:05:31 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:05:31 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:05:31 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:05:31 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:05:31 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:05:31 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:05:31 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:05:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:32 INFO - Downloading blessings-1.6.tar.gz 12:05:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:05:32 INFO - Installing collected packages: blessings 12:05:32 INFO - Running setup.py install for blessings 12:05:32 INFO - Successfully installed blessings 12:05:32 INFO - Cleaning up... 12:05:32 INFO - Return code: 0 12:05:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:05:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:05:33 INFO - Reading from file tmpfile_stdout 12:05:33 INFO - Current package versions: 12:05:33 INFO - blessings == 1.6 12:05:33 INFO - blobuploader == 1.2.4 12:05:33 INFO - docopt == 0.6.1 12:05:33 INFO - functools32 == 3.2.3-2 12:05:33 INFO - jsonschema == 2.5.1 12:05:33 INFO - manifestparser == 1.1 12:05:33 INFO - mozInstall == 1.13 12:05:33 INFO - mozcrash == 1.0 12:05:33 INFO - mozdebug == 0.1 12:05:33 INFO - mozdevice == 0.51 12:05:33 INFO - mozfile == 1.2 12:05:33 INFO - mozhttpd == 0.7 12:05:33 INFO - mozinfo == 0.10 12:05:33 INFO - mozleak == 0.1 12:05:33 INFO - mozlog == 3.4 12:05:33 INFO - moznetwork == 0.27 12:05:33 INFO - mozprocess == 0.25 12:05:33 INFO - mozprofile == 0.28 12:05:33 INFO - mozrunner == 6.13 12:05:33 INFO - mozscreenshot == 0.1 12:05:33 INFO - mozsystemmonitor == 0.3 12:05:33 INFO - moztest == 0.8 12:05:33 INFO - mozversion == 1.4 12:05:33 INFO - psutil == 3.1.1 12:05:33 INFO - requests == 1.2.3 12:05:33 INFO - wsgiref == 0.1.2 12:05:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:05:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:33 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 0x10bef6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10befa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe024b7460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc4cd30>, '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 0x10bc16cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc3dbe8>, '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.gEYUtR9xDo/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.q1jzIBRHRX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:05:33 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:05:33 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:05:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gEYUtR9xDo/Render', 12:05:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:33 INFO - 'HOME': '/Users/cltbld', 12:05:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:33 INFO - 'LOGNAME': 'cltbld', 12:05:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:33 INFO - 'MOZ_NO_REMOTE': '1', 12:05:33 INFO - 'NO_EM_RESTART': '1', 12:05:33 INFO - 'PAGER': '/bin/cat', 12:05:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:33 INFO - 'PWD': '/builds/slave/test', 12:05:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:33 INFO - 'SHELL': '/bin/bash', 12:05:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1jzIBRHRX/Listeners', 12:05:33 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 12:05:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:33 INFO - 'USER': 'cltbld', 12:05:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:33 INFO - 'XPC_FLAGS': '0x0', 12:05:33 INFO - 'XPC_SERVICE_NAME': '0', 12:05:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:33 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:05:33 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:05:33 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:05:33 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:05:33 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:05:33 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:05:33 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:05:33 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:05:33 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:05:33 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:05:33 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:05:33 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:05:33 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:05:33 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:05:34 INFO - Downloading marionette_driver-1.4.0.tar.gz 12:05:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 12:05:35 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:05:35 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:05:35 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:05:35 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:05:35 INFO - Installing collected packages: marionette-driver 12:05:35 INFO - Running setup.py install for marionette-driver 12:05:35 INFO - Successfully installed marionette-driver 12:05:35 INFO - Cleaning up... 12:05:35 INFO - Return code: 0 12:05:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:05:35 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:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:35 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:05:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:35 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 0x10bef6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10befa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe024b7460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc4cd30>, '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 0x10bc16cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc3dbe8>, '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.gEYUtR9xDo/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.q1jzIBRHRX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:05:35 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:05:35 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:05:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gEYUtR9xDo/Render', 12:05:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:35 INFO - 'HOME': '/Users/cltbld', 12:05:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:35 INFO - 'LOGNAME': 'cltbld', 12:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:35 INFO - 'MOZ_NO_REMOTE': '1', 12:05:35 INFO - 'NO_EM_RESTART': '1', 12:05:35 INFO - 'PAGER': '/bin/cat', 12:05:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:35 INFO - 'PWD': '/builds/slave/test', 12:05:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:35 INFO - 'SHELL': '/bin/bash', 12:05:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1jzIBRHRX/Listeners', 12:05:35 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 12:05:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:35 INFO - 'USER': 'cltbld', 12:05:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:35 INFO - 'XPC_FLAGS': '0x0', 12:05:35 INFO - 'XPC_SERVICE_NAME': '0', 12:05:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:05:35 INFO - Cleaning up... 12:05:35 INFO - Return code: 0 12:05:35 INFO - Running post-action listener: _resource_record_post_action 12:05:35 INFO - Running post-action listener: _start_resource_monitoring 12:05:35 INFO - Starting resource monitoring. 12:05:35 INFO - [mozharness: 2017-06-21 19:05:35.516688Z] Finished create-virtualenv step (success) 12:05:35 INFO - [mozharness: 2017-06-21 19:05:35.517738Z] Running install step. 12:05:35 INFO - Running pre-action listener: _resource_record_pre_action 12:05:35 INFO - Running main action method: install 12:05:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:05:35 INFO - Reading from file tmpfile_stdout 12:05:35 INFO - Detecting whether we're running mozinstall >=1.0... 12:05:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:05:35 INFO - Reading from file tmpfile_stdout 12:05:35 INFO - Output received: 12:05:35 INFO - Usage: mozinstall [options] installer 12:05:35 INFO - Options: 12:05:35 INFO - -h, --help show this help message and exit 12:05:35 INFO - -d DEST, --destination=DEST 12:05:35 INFO - Directory to install application into. [default: 12:05:35 INFO - "/builds/slave/test"] 12:05:35 INFO - --app=APP Application being installed. [default: firefox] 12:05:35 INFO - mkdir: /builds/slave/test/build/application 12:05:35 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:05:35 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:05:57 INFO - Reading from file tmpfile_stdout 12:05:57 INFO - Output received: 12:05:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:05:57 INFO - Running post-action listener: _resource_record_post_action 12:05:57 INFO - [mozharness: 2017-06-21 19:05:57.890494Z] Finished install step (success) 12:05:57 INFO - [mozharness: 2017-06-21 19:05:57.890623Z] Running setup-mitmproxy step. 12:05:57 INFO - Running pre-action listener: _resource_record_pre_action 12:05:57 INFO - Running main action method: setup_mitmproxy 12:05:57 INFO - Skipping: mitmproxy is not required 12:05:57 INFO - Running post-action listener: _resource_record_post_action 12:05:57 INFO - [mozharness: 2017-06-21 19:05:57.891278Z] Finished setup-mitmproxy step (success) 12:05:57 INFO - [mozharness: 2017-06-21 19:05:57.891387Z] Running run-tests step. 12:05:57 INFO - Running pre-action listener: _resource_record_pre_action 12:05:57 INFO - Running main action method: run_tests 12:05:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:05:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:05:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:05:57 INFO - 2017-06-21 12:05:57.906 screenresolution[2363:29064] starting screenresolution argv=screenresolution get 12:05:57 INFO - 2017-06-21 12:05:57.908 screenresolution[2363:29064] Display 0: 1600x1200x32@60 12:05:57 INFO - 2017-06-21 12:05:57.914 screenresolution[2364:29066] starting screenresolution argv=screenresolution list 12:05:57 INFO - Available Modes on Display 0 12:05:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:05:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:05:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:05:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:05:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:05:58 INFO - Intel Iris: 12:05:58 INFO - Chipset Model: Intel Iris 12:05:58 INFO - Type: GPU 12:05:58 INFO - Bus: Built-In 12:05:58 INFO - VRAM (Dynamic, Max): 1536 MB 12:05:58 INFO - Vendor: Intel (0x8086) 12:05:58 INFO - Device ID: 0x0a2e 12:05:58 INFO - Revision ID: 0x0009 12:05:58 INFO - Displays: 12:05:58 INFO - Display: 12:05:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:05:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:05:58 INFO - Display Serial Number: 1600x1200 60 12:05:58 INFO - Main Display: Yes 12:05:58 INFO - Mirror: Off 12:05:58 INFO - Online: Yes 12:05:58 INFO - Rotation: Supported 12:05:58 INFO - Return code: 0 12:05:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:05:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:05:58 INFO - Python 2.7.3 12:05:58 INFO - Return code: 0 12:05:58 INFO - grabbing minidump binary from tooltool 12:05:58 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:05:58 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 0x10bc4cd30>, '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 0x10bc16cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc3dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:05:58 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:05:58 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:05:58 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:05:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:05:58 INFO - Return code: 0 12:05:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:05:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:05:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:05:58 INFO - ENV: RUST_BACKTRACE is now 1 12:05:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:05:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:05:58 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-0381', '--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:05:58 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-0381 --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:05:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gEYUtR9xDo/Render', 12:05:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:05:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:05:58 INFO - 'HOME': '/Users/cltbld', 12:05:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:05:58 INFO - 'LOGNAME': 'cltbld', 12:05:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:05:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:05:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:58 INFO - 'MOZ_NO_REMOTE': '1', 12:05:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:05:58 INFO - 'NO_EM_RESTART': '1', 12:05:58 INFO - 'PAGER': '/bin/cat', 12:05:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:05:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:58 INFO - 'PWD': '/builds/slave/test', 12:05:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:05:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:05:58 INFO - 'RUST_BACKTRACE': '1', 12:05:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:05:58 INFO - 'SHELL': '/bin/bash', 12:05:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q1jzIBRHRX/Listeners', 12:05:58 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 12:05:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:05:58 INFO - 'USER': 'cltbld', 12:05:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:05:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:05:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:58 INFO - 'XPC_FLAGS': '0x0', 12:05:58 INFO - 'XPC_SERVICE_NAME': '0', 12:05:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:05:58 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-0381', '--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:05:58 INFO - mozversion application_buildid: 20170620132059 12:05:58 INFO - mozversion application_changeset: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 12:05:58 INFO - mozversion application_display_name: Nightly 12:05:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:05:58 INFO - mozversion application_name: Firefox 12:05:58 INFO - mozversion application_remotingname: firefox 12:05:58 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 12:05:58 INFO - mozversion application_vendor: Mozilla 12:05:58 INFO - mozversion application_version: 56.0a1 12:05:58 INFO - mozversion platform_buildid: 20170620132059 12:05:58 INFO - mozversion platform_changeset: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 12:05:58 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 12:05:58 INFO - mozversion platform_version: 56.0a1 12:05:58 INFO - using testdate: 1498071958 12:05:58 INFO - actual date: 1498071958 12:05:58 INFO - starting webserver on 'localhost:56959' 12:05:58 INFO - SUITE-START | Running 2 tests 12:05:58 INFO - TEST-START | dromaeo_css 12:05:58 INFO - operating with platform_type : mac_ 12:05:58 INFO - Initialising browser for dromaeo_css test... 12:05:58 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56959/getInfo.html -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmpGaJqvi/profile 12:05:58 INFO - TEST-INFO | started process 2374 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56959/getInfo.html -foreground) 12:06:03 INFO - TEST-INFO | 2374: exit 0 12:06:03 INFO - Browser initialized. 12:06:03 INFO - Running cycle 1/1 for dromaeo_css test... 12:06:03 INFO - TEST-INFO | started process 2380 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmpGaJqvi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:06:05 INFO - PID 2380 | 2017-06-21 12:06:05.139 plugin-container[2381:29452] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 12:06:05 INFO - PID 2380 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:06:05 INFO - PID 2380 | 2017-06-21 12:06:05.140 plugin-container[2381:29452] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 12:06:05 INFO - PID 2380 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:06:05 INFO - PID 2380 | 2017-06-21 12:06:05.140 plugin-container[2381:29452] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:06:05 INFO - PID 2380 | 2017-06-21 12:06:05.887 plugin-container[2382:29509] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x984b, name = 'com.apple.tsm.portname' 12:06:05 INFO - PID 2380 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:06:05 INFO - PID 2380 | 2017-06-21 12:06:05.887 plugin-container[2382:29509] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0f, name = 'com.apple.CFPasteboardClient' 12:06:05 INFO - PID 2380 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:06:05 INFO - PID 2380 | 2017-06-21 12:06:05.887 plugin-container[2382:29509] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:06:15 INFO - PID 2380 | RSS: Main: 250257408 12:06:15 INFO - PID 2380 | 12:09:21 INFO - PID 2380 | Cycle 1(1): loaded http://localhost:56959/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56959/tests/dromaeo/cssquery-ext.html) 12:09:23 INFO - PID 2380 | RSS: Main: 266805248 12:09:23 INFO - PID 2380 | 12:12:25 INFO - PID 2380 | Cycle 1(1): loaded http://localhost:56959/tests/dromaeo/cssquery-ext.html (next: http://localhost:56959/tests/dromaeo/cssquery-jquery.html) 12:12:26 INFO - PID 2380 | RSS: Main: 270139392 12:12:26 INFO - PID 2380 | 12:15:27 INFO - PID 2380 | Cycle 1(1): loaded http://localhost:56959/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56959/tests/dromaeo/cssquery-mootools.html) 12:15:28 INFO - PID 2380 | RSS: Main: 278757376 12:15:28 INFO - PID 2380 | 12:18:30 INFO - PID 2380 | Cycle 1(1): loaded http://localhost:56959/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56959/tests/dromaeo/cssquery-prototype.html) 12:18:30 INFO - PID 2380 | RSS: Main: 276611072 12:18:30 INFO - PID 2380 | 12:21:33 INFO - PID 2380 | Cycle 1(1): loaded http://localhost:56959/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56959/tests/dromaeo/cssquery-yui.html) 12:21:34 INFO - PID 2380 | RSS: Main: 279257088 12:21:34 INFO - PID 2380 | 12:24:35 INFO - PID 2380 | Cycle 1(1): loaded http://localhost:56959/tests/dromaeo/cssquery-yui.html (next: http://localhost:56959/tests/dromaeo/cssquery-dojo.html) 12:24:36 INFO - PID 2380 | RSS: Main: 286126080 12:24:36 INFO - PID 2380 | 12:24:36 INFO - PID 2380 | __start_tp_report 12:24:36 INFO - PID 2380 | _x_x_mozilla_page_load 12:24:36 INFO - PID 2380 | _x_x_mozilla_page_load_details 12:24:36 INFO - PID 2380 | |i|pagename|runs| 12:24:36 INFO - PID 2380 | |0;dojo.html;3584.444411116278;3640.672339489445;3658.2866341063486;3678.896331100671;3706.573744019437;5312.78748850046;5711.314642242926;6110.908336374958;6455.2576453707725;6480.157579514668;6388.329225431321;6521.119648847404;6580.111684922534;6584.604923704581;6599.769008084706;5708.828735137931;5738.880918220949;5761.222234998285;5840.737166827482;5918.319393269764;5792.65244085352;5910.734016969225;5926.674032928187;5939.495142912858;5966.105084237356;5637.069883469223;5699.2590963174935;5740.052891272935;5772.30732312121;5796.62321949072;5674.375818659944;5693.3452652945025;5714;5775.075987841925;5811.912821307684;5591.329040515124;5647.265855438808;5714.371419143891;5762.337331206881;5768.192453056576;6515.674216289167;6524.119243902107;6564.1138446309615;6585.835354116138;6606.669666516703;5910.7931222407005;6907.861842763167;7149.392301654366;7158.606276654782;7210.531315464478;7163.247858974784;7168.60572668503;7195.748148814819;7240.782776516713;7246.021787058785;6600.5049621278595;6646.368594983469;6703.430208432338;6720.865582688417;6723.899141512783;7189;7424.554526728413;7425.888611670829;7437.665305061177;7443.292887175709;6944.097267355211;6950.374466298057;6980.371766541036;6980.825479363057;6981.30186981308;7048.682809273595;7122.394596459359;7180.7845764627145;7258.3104605062435;7285.198628150899;7089.184743754431;7103.786886393416;7120.857582848314;7121.359077683033;7123.7506687265695;5902.61632993854;6887.586744795329;7009.7897063088185;7103.218645948941;7123.038389817309;5492.5605951523785;5496.5053145217125;5533.391326954032;5542.501174894279;5573.470520300565;4882.096812089775;4952.653314267966;4968.826091086795;4994.350734404504;5011.724355160502;6682.3317668232785;6710.932890671128;6713.966430167818;6715.059891615178;6743.15710536183;5912.379200184005;7245.492815502864;7256.818579535448;7258.056452661226;7264.709411623581;6459.806205813833;6544.411003009657;6682.131322928086;6705.195376554844;6714.597124172566;6172.9074063889975;6195.349488303754;6216.751329946752;6273.7176827042895;6313.84215394628;7293.562386256842;7318.597477138595;7323.377512911462;7329.083864516935;7348.77953661391;6363.3000369958345;6711.798646040626;7134.85730285381;7152.785416437515;7175.497715160098;7278.818029549304;7297.744578939712;7299.306565876233;7303.52527085759;7319.4144468443465;5697.971510142423;6878.896816547655;6971.407430368476;6974;7001.684924178322;5782.595218334676;7192.892106618509;7217.819554511179;7259.564426134449;7299.197088320174;7047.40097091753;7058.435325174077;7064.505484616233;7083.574985500886;7098.077249957679;6920.688569014507;6940.444764418733;7011.1586609607175;7037.225905150532;7044.6125463100025;6714.798556043326;6834.214065382345;6838.04267402574;6838.760643377458;6841.62371069596;6863.691133899087;6880.65596720152;6896.620685862325;6971.058907047483;6996.930030699831;6835.4189893859575;6865.313468653095;6870.553414028072;6904.482163837632;6936.167659880644 12:24:36 INFO - PID 2380 | |1;ext.html;896.1531352871536;903.6349004741176;957.3155194036262;982.3614650477184;983;37576.624233757655;38808;38819.22361552767;38870.61129388705;38894;38955.026124346834;39716;39778.80110599443;39839.00402489939;39907.40138897919;8510.319174466043;8578.185072418142;8646.221840034395;8682.218600325969;8693.782655433633;8438.662453501853;8533.018702849158;8543.102974187714;8551.273141782956;8581.442206256605;8510.063892971768;8556.74329770108;8559.27246184072;8564.528950907697;8598.140185981383;8564;8573.485590864568;8591.226789588905;8592.312614990817;8595.785105372353;7501.912222727674;7976.800579985489;8312.709055183039;8406.201410865959;8408.537530435824;36849.26301473982;36989;37007;37110.25779484422;37132.814335928146;3559.2169722660974;3565.0196196046036;3565.4117070683305;3601.2077342984503;3601.513795637608;30234.546481802572;30581.541276880864;30645.23386915345;30670.386592268253;30679;2607.4002979314864;3045.649750278702;3130.5930229070077;3159.5418664293547;3191.1543440988157;282983;284756.9999999979;287489;292371;292962;40404;47504.04991900142;47751.5224847754;47788;48622.78443038881;45901.31148032782;51188.74405627948;51792.48207517952;51827.48172518302;51959;14522.983391162728;14550.708985820223;14631.634209144857;14665.780013299809;14667.046641968238;11938.94030529859;12161.878381216075;12174.878251217551;12181.817272740916;12195.268283902817;9830.705078847646;11756.35340056288;11832.408379580986;11882.702932426586;11935.880641193477;4790.856274311776;5358.892822143535;5428.538574221236;5434.83695489136;5501.037318469252;957.7557722780647;1010.661428421487;1025.9743506412399;1076.0853274716428;1077.8059949209219;18947.33684321042;19497.610047799248;20035.09315683428;22558.436039098826;23803.285901422983;1113.4154568851418;1128.6162704680285;1133.484264659578;1135.5684839760838;1140.7547377313917;10144.644937427154;10537.63118290856;10869;11098.502836861802;12022.879771202175;294335;341285;341743;341997;342218;52971.73514132405;53563.19655205175;53679.926401472534;53691.19463208055;53732.46267537353;1470.8235011798652;1546.373718643951;1568.8195857476537;1577.7712231726334;1580.4231455518652;21574.980559488016;21973.13228375606;22724.091036359023;22726;23392.415189620395;36928.81535592305;37049;37140.628593713715;37177;37381.065473363386;271397;272701;275328;275815;277232;29766.80932762665;30396.84801575978;30598.541021884244;33693.32613347768;34020.65979340274;39005.02487437837;42436;42437;44608.10783784371;44838;27456.588151177348;27883.302917427227;28363;29644.01487877412;30925.69074309228;29263.268418289714;29709.257268567595;30043.549346760254;30449.39101217919;30502.542461862646;36762;36842;36874.8156259217;36882;36993.07517312089;29182.27044323909;29846.552301715925;30030.699693003666;30192;30519.084427467213;22021.449463763533;23876.806159691598;24108.156214532406;24192.87903560471;24200.515989679756 12:24:36 INFO - PID 2380 | |2;jquery.html;526.0872386409578;546.8687514996401;553.9639923404978;565.9632123911948;572.6792995922282;22899.427514312152;23410;23756.40608984774;23764.999999999956;23795.64306535399;19242.51893702658;26790.866045669794;27027.729722702767;27052.72947270527;27078.32304192391;6755.459563234942;6899.517033807636;6903.482238832083;6918.861622767567;6920.861582768342;6808.795736127924;6814.250432452426;6837.1795384553925;6857.417119544844;6863.862722745542;4967.528084831935;6229.968850155743;6477;6621.536492445531;6626.237982631987;6533.085368048477;6636.270010298887;6639.867202655944;6642.202935647705;6647.567908086007;6153.846153846145;6164.660943648098;6179.845503862394;6183.258009038899;6192.535559832985;31339.686603133905;31559;31614;31636.683633163833;32123.83938080318;2185.27885797688;2241.5068684889297;2248.2580748353166;2249.561335539554;2250.0999600159903;19212.903935480233;19649.31227407034;19708.80291197084;19733.802661973485;19839.702404463944;2217.889105544716;2253.3352660965056;2282.315305408371;2301.873396963166;2304.631258998569;188596;190730;192902;193249.9999999986;195123;13606;16226;16247.59381015484;16403.507894763177;16488.505344839676;20072.397828065183;22773.08907643709;22827.543449130924;22882.771172288398;22931;10193.388396696222;10321.948390258001;10338.793224135476;10409.895901041044;10421.166306695597;9295.953520232355;9303.906960930304;9357.812843743222;9375.578098985561;9450.669226577036;9612.423254604746;9751.707448776406;9790.902090979142;9816.460094694718;9829.115379615869;2321.025169428844;2350.471143992588;2411;2591.248537924019;2744.286485513741;949.7910459698855;952.4427560937778;971.708487453761;982.7837875667342;1026.650938680852;21286.36140915775;21527.784722152577;27511.58732619012;29419.705802942124;29434.558481622793;1033.7105610429091;1049.4333060147587;1054.2988912373187;1061.177587369779;1064.499685147979;11887.940560297144;12044.855738704984;12142;12177.939110304393;12515.624531264077;514140;523663;528714;568104;576180.5524320701;165353;166172;166189;167649;169852;1202.05038019965;1229.6372570091844;1248.182440501473;1261.3188878005958;1264.6522206393315;23112.191073313024;23628.763712362655;24563.017479301343;24692.876535617925;24737.876310619053;67229.66385168042;68230;72966;74200;76633.61683191548;88650;92232;92566.53716731373;93498.13003740023;94926.52536737273;12298.262104273772;12438.37808109609;12445.25328480294;12537.686557836127;12604.936975315064;16620;17917.73123403124;18778.248870045045;19097.331593394163;19166.8083319165;11499.597514086967;14047.508337208148;15264.23678816077;15616.843831561539;15758.921205393899;13476.460941562227;13964.30178491051;14090.968148155453;14258.643533911736;14601.904857135969;16249.67500650004;16405.83594164043;16412.671746564763;16463.91768041152;16513.504594862174;12478.688032799253;12549.121561490609;12618.55835045769;12625;12715.936420318209;11860.466279017637;14561;15217.847821521644;15502.147381894105;15761 12:24:36 INFO - PID 2380 | |3;mootools.html;1355.898307626928;1549.5273941447854;1551.4957638767403;1553.5028790786946;1560.0795530636922;11267.492962816672;15738.606534836636;15757.448489302848;15785.342539033409;15812.20938953052;12969.93515032426;13358.532451364204;13437.260950647735;14276.928615356934;14596.489122880701;2882.8126171798817;2945.631796025497;2948.9557656635166;2956.896508622199;2959.2601849537614;2865.1404578626434;2893.291143669793;2897.4350001749667;2902.3614804742924;2906.128161551526;2857.4427986542632;2874.985625071882;2900.2169414258137;2901.332693480501;2901.4777340078776;2836.673782515006;2837.0779496663563;2854.9000784972636;2858.857057147134;2859.1851322373022;2368.2421625079983;2821.887124515017;2833.0367674990603;2833.263351528597;2837.4750671125908;14995.925020374938;15282.465113720966;15342.925995180341;15358.923205383902;15364.154971476564;6736.2590115087305;8645.438046526955;8653.134686531359;8655.6970506032;8659.870101948476;4928.310036594879;5182.559482443997;5330.173823057432;5357.464253574612;5434.157705555605;1430.763923952557;1455.6317061961722;1483.2435457916433;1493.5071426429251;1537.6847746212;20890.5821883563;22840.885795570917;24208.757912420828;24295.392615184763;24433.022679092814;7657.463977521631;7853.625615517261;7854.6858125674335;8020.19798020205;8041.356691464669;15304.693906122038;18879.150438230037;19119.71320430195;19188.520286992938;19197.8080219196;6947.7220911162985;7040.2607726189035;7905.881411778828;8314;8399.286060684788;6481;7547.849043019219;7572.167061623217;7651.5409075455655;7820.413468989848;4664.043871006435;5453.400125986138;5560.638558493685;5632.436756324335;5683.829485115453;1359.714459963399;1495.2673190136754;1633.338497908349;1645.1210381831795;1655.9751603725954;860.6815478273079;861.5821326656567;884.2395539835736;885.1104639836923;905.6015353244551;6852.6231057292325;7070.116235470567;7403.370713489306;7657;7787.844243115106;714.7284032067781;737.2664199121908;841.7979684875709;905.3119629081945;959.9184069354044;4932.8766780830765;4961.231009193581;5223;5354.571634269249;5473.917891231635;19405.385226522343;20974.951252437317;21219.575608487743;21428.357149285548;21550.922453877243;19942.20231190765;21159.25942592002;21185.046672899447;21242.044108015176;21288.467788305417;10262.897371026344;10288.228382871164;10319.74200644975;10323;10363.63727269542;7481.625918704151;8349.33205343559;8469.237768600855;8685.956570217108;9227.215686666708;17764.644707106043;18194.181261842983;18372.632547349247;18897.622047558696;19038.33365832189;17871.642567148843;18842.905785470986;19034.428967131007;19236.422907312804;19318.93745843992;9580.425174489554;10924.235303528676;11146.832797507881;11344.489497972447;11859.940700296444;13733.725325493633;14393.352299146354;14460.349284282021;14497.78253326222;14527.782083268548;10786.676399708022;10787.244892857425;11146.164037697043;11380.886191137983;11449.141314401286;10396.740081498023;10604.946975265382;11037.944810275898;11085.94457027742;11226.494807733836;9790.412575245507;9807;9829.754256143367;9879.259055571005;9901.752456188653;6579.736810527525;6821.556598821061;7329.853402931805;7393.408527317909;7448.217937116741;7146.9285307146265;7188.317109874553;7474.177840437439;7508.0990281166605;7508.0990281166605 12:24:36 INFO - PID 2380 | |4;prototype.html;437.6586262715081;468.6531966344906;478.8850675837798;498.6683855236268;504.8132191089296;5592.944070559293;6377.011563207699;6430.3569643035735;6460.838479038026;6517.989711594699;6070;6312.33720459352;6353.868516881209;6356.078368636545;6386.840328991778;3959.9406008909746;4190.769507677077;4771.260454629521;4787.593054590364;4800.591949684281;3660.5790334111653;4263.82744745196;4821.180399332122;4835.830745923893;4845.830395936143;3601.8019008954498;4361.60745532902;4534.183846907556;4768.141734487791;4813.855584332476;4560.312684306795;4746.240601503778;4747.192977193886;4777.163996300668;4805.591524720403;4191.3293872980175;4210.9578904210875;4220.177065472234;4228.767417792051;4229.788510574459;4793.921367692276;5411.917616476681;5481.725913704299;6139;6288.126701806137;5189.806344540777;5723.484886360206;5723.599348045638;5730.885382292331;5789.826305210809;6846.726130954806;6865.244823069458;6890.276520965276;6924.3421874921805;6925.930740692579;4836.951630483721;4847.521505940686;4853.733044682541;4866.172750632401;4871.368091689288;6032.9698351508405;7069.752558660422;7193.928060719378;7220.67506962188;7309.634518274065;4937.530934561211;4959.876003099951;4963.478834722375;4996.525330093636;5026.070177017264;5052.418971818288;5060.316857224301;5068.037072956126;5093.516115968977;5097.235414687826;4363.2800587903275;5280.445553216934;5696.658200507983;5704.657720536781;5736.254286942671;6903.068085808452;6915.4121899638085;6923.5460553283365;6935.6878940447805;6970.721171153097;1572.2846105022188;1718.2954988454924;1721.2942693495606;1740.85202757767;1748.8163742807076;1171.625079974417;1177.140687298277;1179;1184.7808155491261;1185.158537438411;5693.658380497183;6321.810345689637;6439.9356006440275;6441.613503189824;6500.414962653384;5723.656580605177;6812.8978065328065;7017.614031228331;7048.718051277891;7067.339175293801;6186.628802271878;6369.490440764727;6375.6174629522375;6392.264889537762;6395.904061439082;3652.342578335925;3679.8712045078296;3681.8711345102793;3683.189698266377;3707.573629032696;6149.41580549847;6931.826704332331;7247.420206383476;7251.5286506376915;7278.599677017814;6097.085437184458;6432.070378886316;7247.637618119178;7257.564546127249;7283.70865165398;5907.556933230025;6788.287229840895;6945.93054069463;7219.639018049076;7240.96379518099;6611.504137189826;6988.510804243654;7034.226235114031;7070.858582828418;7088.326608972139;5496.367917689517;6601.669916504059;6976.930230697628;6995.440364770704;7070.681819318246;6275.246970363403;7169.530246299391;7219;7220.89168662481;7275.34521893011;4090.754554726726;4332.8266869326135;4821.54195351441;4920.507949205051;4965.875853103556;6546.574472659262;6565.222604108545;7062.329078737512;7131.786046418616;7165.319294666998;5621.8594535136945;5956.672383018976;6074.119252708421;6713.429358504386;6714.597124172566;5997.430244126801;6432.292447830829;6689.531732778659;6810.387065164159;6841.828954276183;5695.145728140812;6650.401463868275;6702.262751097278;6703.530752847254;6761.898571521333;5251.973740131275;6851.486138539729;6851.794446166623;6851.828704282433;6854.34883686049;5559.555235581225;6573.408393244631;6628;6643.202815661958;6670.433013193933 12:24:36 INFO - PID 2380 | |5;yui.html;794.6146119132221;823.4318320358952;832.1096426823298;841.6927821345204;869.5826003518307;17024;17385.47843564692;17416.82583174168;17432.651346973085;19238.32665856692;18884.905575472138;19391.70912436311;19481.610367792637;19533;19755.506112347197;940.0975063938627;974.1914211204684;1016.7356487313315;1065.6483360491038;1073.1468482556386;1064.2603390643505;1065.046783129099;1065.3874022437092;1066.802641511319;1074.1138560687425;1119.4850368830348;1127.560251501915;1130.3331034689531;1131.3664347965125;1134.4044376702216;1120.6918097523164;1124.173732306754;1125.302312566206;1127.9718007049848;1142.3716955674388;744.118219909409;770.9730159444449;831.6465502161557;837.8324335132937;853.714005808055;18121.818781812144;18148.364807231814;18188.90905545464;18368.26526938921;18369.357072502396;16600.25298861542;17201.483955481355;17569.20938557768;17933.19300631475;18687.252509899587;15061.17163556004;15100.924495377452;15102.169380684058;15130.848691513054;15243.16162611056;3660.871869484555;3697.242065376591;3721.3115573618697;3727.235916637083;3730.5336832895887;158759;159920;160184;162494;165234;12915.579286278502;13355.131916425595;14137.575872723835;14254.643633909236;14280.357383917748;27371.315717106834;27549.449011019668;27847.582286265722;28151.155465336073;28187.57718634222;7980.441369104223;7989;8098.190180981855;8122.147174546588;8123.147069557729;3636.9272614547563;3758.4550240215017;3782.8486860525823;4000.4199391088127;4096.262672718939;4824.831130910401;5372.5970552207955;5404.108322126884;5546.639468434539;5565.888682226333;1100.1308965916996;1131.0951239008925;1133.9943300283446;1163.2322667039714;1164.1210885781181;651.5406638319973;669.2872091222832;674.2785219814751;690.547691262224;694.1254019934919;11362.659120226406;12196.81704774429;12683.433963773034;12734.172278801849;12788.552400665933;635.9968200159062;645.2643985856128;648.5946283572767;667.8564108716649;694.6526736631685;5624.128260119688;5648.181013752983;5656.971715141398;5698.800541981011;5940.150073242949;130476;135293.32353338366;137299.31350343185;137410;142157;119083;120667;121766.3911680436;126327;131105;106673;106994.46502767436;107417.92582074236;107549.46225268823;110816;11290.548378064785;11320.26418282809;11338.263012904135;11371.374574398487;11803.23278986863;39702.801485992386;44798.776006121065;45098.87252818706;50446.49553504565;51358.97282054263;67719.30701732496;68165;71690;72244.27755722376;72304;14877.330520126838;15133.243337832844;15340.232988350761;15395.61510962235;15498.5350439487;13604.591862243753;16014.316392269147;16825.663486730442;20345.79654203439;21070.789292107496;18450.35423760162;18486.722699159254;18678.439646810617;18758.53103672419;18863.96248206362;18268.634627307645;18818.435446936615;18910.054497275356;19147;19328.516787080436;17702.468925932244;18205.908970455064;19082.809171908102;19130.808691912902;19291.324803631807;10980.670579882617;12248.755024899274;13093.018023648456;13317.134386264861;13718.588442346745;13198.94408447341;13941.58175254744;14097;14184.29078546048;14846.109233446028 12:24:36 INFO - PID 2380 | __end_tp_report 12:24:36 INFO - PID 2380 | __start_cc_report 12:24:36 INFO - PID 2380 | _x_x_mozilla_cycle_collect,443 12:24:36 INFO - PID 2380 | __end_cc_report 12:24:36 INFO - PID 2380 | __startTimestamp1498073076206__endTimestamp 12:24:36 INFO - PID 2380 | 12:24:36 INFO - PID 2380 | ------- Summary: start ------- 12:24:36 INFO - PID 2380 | Number of tests: 6 12:24:36 INFO - PID 2380 | 12:24:36 INFO - PID 2380 | [#0] dojo.html Cycles:165 Average:6506.95 Median:6743.16 stddev:813.76 (12.1%) stddev-sans-first:783.29 12:24:36 INFO - PID 2380 | Values: 3584.4 3640.7 3658.3 3678.9 3706.6 5312.8 5711.3 6110.9 6455.3 6480.2 6388.3 6521.1 6580.1 6584.6 6599.8 5708.8 5738.9 5761.2 5840.7 5918.3 5792.7 5910.7 5926.7 5939.5 5966.1 5637.1 5699.3 5740.1 5772.3 5796.6 5674.4 5693.3 5714.0 5775.1 5811.9 5591.3 5647.3 5714.4 5762.3 5768.2 6515.7 6524.1 6564.1 6585.8 6606.7 5910.8 6907.9 7149.4 7158.6 7210.5 7163.2 7168.6 7195.7 7240.8 7246.0 6600.5 6646.4 6703.4 6720.9 6723.9 7189.0 7424.6 7425.9 7437.7 7443.3 6944.1 6950.4 6980.4 6980.8 6981.3 7048.7 7122.4 7180.8 7258.3 7285.2 7089.2 7103.8 7120.9 7121.4 7123.8 5902.6 6887.6 7009.8 7103.2 7123.0 5492.6 5496.5 5533.4 5542.5 5573.5 4882.1 4952.7 4968.8 4994.4 5011.7 6682.3 6710.9 6714.0 6715.1 6743.2 5912.4 7245.5 7256.8 7258.1 7264.7 6459.8 6544.4 6682.1 6705.2 6714.6 6172.9 6195.3 6216.8 6273.7 6313.8 7293.6 7318.6 7323.4 7329.1 7348.8 6363.3 6711.8 7134.9 7152.8 7175.5 7278.8 7297.7 7299.3 7303.5 7319.4 5698.0 6878.9 6971.4 6974.0 7001.7 5782.6 7192.9 7217.8 7259.6 7299.2 7047.4 7058.4 7064.5 7083.6 7098.1 6920.7 6940.4 7011.2 7037.2 7044.6 6714.8 6834.2 6838.0 6838.8 6841.6 6863.7 6880.7 6896.6 6971.1 6996.9 6835.4 6865.3 6870.6 6904.5 6936.2 12:24:36 INFO - PID 2380 | 12:24:36 INFO - PID 2380 | [#1] ext.html Cycles:180 Average:44544.49 Median:341870.00 stddev:78646.65 (23.0%) stddev-sans-first:78798.99 12:24:36 INFO - PID 2380 | Values: 896.2 903.6 957.3 982.4 983.0 37576.6 38808.0 38819.2 38870.6 38894.0 38955.0 39716.0 39778.8 39839.0 39907.4 8510.3 8578.2 8646.2 8682.2 8693.8 8438.7 8533.0 8543.1 8551.3 8581.4 8510.1 8556.7 8559.3 8564.5 8598.1 8564.0 8573.5 8591.2 8592.3 8595.8 7501.9 7976.8 8312.7 8406.2 8408.5 36849.3 36989.0 37007.0 37110.3 37132.8 3559.2 3565.0 3565.4 3601.2 3601.5 30234.5 30581.5 30645.2 30670.4 30679.0 2607.4 3045.6 3130.6 3159.5 3191.2 282983.0 284757.0 287489.0 292371.0 292962.0 40404.0 47504.0 47751.5 47788.0 48622.8 45901.3 51188.7 51792.5 51827.5 51959.0 14523.0 14550.7 14631.6 14665.8 14667.0 11938.9 12161.9 12174.9 12181.8 12195.3 9830.7 11756.4 11832.4 11882.7 11935.9 4790.9 5358.9 5428.5 5434.8 5501.0 957.8 1010.7 1026.0 1076.1 1077.8 18947.3 19497.6 20035.1 22558.4 23803.3 1113.4 1128.6 1133.5 1135.6 1140.8 10144.6 10537.6 10869.0 11098.5 12022.9 294335.0 341285.0 341743.0 341997.0 342218.0 52971.7 53563.2 53679.9 53691.2 53732.5 1470.8 1546.4 1568.8 1577.8 1580.4 21575.0 21973.1 22724.1 22726.0 23392.4 36928.8 37049.0 37140.6 37177.0 37381.1 271397.0 272701.0 275328.0 275815.0 277232.0 29766.8 30396.8 30598.5 33693.3 34020.7 39005.0 42436.0 42437.0 44608.1 44838.0 27456.6 27883.3 28363.0 29644.0 30925.7 29263.3 29709.3 30043.5 30449.4 30502.5 36762.0 36842.0 36874.8 36882.0 36993.1 29182.3 29846.6 30030.7 30192.0 30519.1 22021.4 23876.8 24108.2 24192.9 24200.5 12:24:36 INFO - PID 2380 | 12:24:36 INFO - PID 2380 | [#2] jquery.html Cycles:180 Average:40052.02 Median:22891.10 stddev:94864.94 (414.4%) stddev-sans-first:95084.65 12:24:36 INFO - PID 2380 | Values: 526.1 546.9 554.0 566.0 572.7 22899.4 23410.0 23756.4 23765.0 23795.6 19242.5 26790.9 27027.7 27052.7 27078.3 6755.5 6899.5 6903.5 6918.9 6920.9 6808.8 6814.3 6837.2 6857.4 6863.9 4967.5 6230.0 6477.0 6621.5 6626.2 6533.1 6636.3 6639.9 6642.2 6647.6 6153.8 6164.7 6179.8 6183.3 6192.5 31339.7 31559.0 31614.0 31636.7 32123.8 2185.3 2241.5 2248.3 2249.6 2250.1 19212.9 19649.3 19708.8 19733.8 19839.7 2217.9 2253.3 2282.3 2301.9 2304.6 188596.0 190730.0 192902.0 193250.0 195123.0 13606.0 16226.0 16247.6 16403.5 16488.5 20072.4 22773.1 22827.5 22882.8 22931.0 10193.4 10321.9 10338.8 10409.9 10421.2 9296.0 9303.9 9357.8 9375.6 9450.7 9612.4 9751.7 9790.9 9816.5 9829.1 2321.0 2350.5 2411.0 2591.2 2744.3 949.8 952.4 971.7 982.8 1026.7 21286.4 21527.8 27511.6 29419.7 29434.6 1033.7 1049.4 1054.3 1061.2 1064.5 11887.9 12044.9 12142.0 12177.9 12515.6 514140.0 523663.0 528714.0 568104.0 576180.6 165353.0 166172.0 166189.0 167649.0 169852.0 1202.1 1229.6 1248.2 1261.3 1264.7 23112.2 23628.8 24563.0 24692.9 24737.9 67229.7 68230.0 72966.0 74200.0 76633.6 88650.0 92232.0 92566.5 93498.1 94926.5 12298.3 12438.4 12445.3 12537.7 12604.9 16620.0 17917.7 18778.2 19097.3 19166.8 11499.6 14047.5 15264.2 15616.8 15758.9 13476.5 13964.3 14091.0 14258.6 14601.9 16249.7 16405.8 16412.7 16463.9 16513.5 12478.7 12549.1 12618.6 12625.0 12715.9 11860.5 14561.0 15217.8 15502.1 15761.0 12:24:36 INFO - PID 2380 | 12:24:36 INFO - PID 2380 | [#3] mootools.html Cycles:180 Average:9170.40 Median:2846.19 stddev:6385.98 (224.4%) stddev-sans-first:6376.90 12:24:36 INFO - PID 2380 | Values: 1355.9 1549.5 1551.5 1553.5 1560.1 11267.5 15738.6 15757.4 15785.3 15812.2 12969.9 13358.5 13437.3 14276.9 14596.5 2882.8 2945.6 2949.0 2956.9 2959.3 2865.1 2893.3 2897.4 2902.4 2906.1 2857.4 2875.0 2900.2 2901.3 2901.5 2836.7 2837.1 2854.9 2858.9 2859.2 2368.2 2821.9 2833.0 2833.3 2837.5 14995.9 15282.5 15342.9 15358.9 15364.2 6736.3 8645.4 8653.1 8655.7 8659.9 4928.3 5182.6 5330.2 5357.5 5434.2 1430.8 1455.6 1483.2 1493.5 1537.7 20890.6 22840.9 24208.8 24295.4 24433.0 7657.5 7853.6 7854.7 8020.2 8041.4 15304.7 18879.2 19119.7 19188.5 19197.8 6947.7 7040.3 7905.9 8314.0 8399.3 6481.0 7547.8 7572.2 7651.5 7820.4 4664.0 5453.4 5560.6 5632.4 5683.8 1359.7 1495.3 1633.3 1645.1 1656.0 860.7 861.6 884.2 885.1 905.6 6852.6 7070.1 7403.4 7657.0 7787.8 714.7 737.3 841.8 905.3 959.9 4932.9 4961.2 5223.0 5354.6 5473.9 19405.4 20975.0 21219.6 21428.4 21550.9 19942.2 21159.3 21185.0 21242.0 21288.5 10262.9 10288.2 10319.7 10323.0 10363.6 7481.6 8349.3 8469.2 8686.0 9227.2 17764.6 18194.2 18372.6 18897.6 19038.3 17871.6 18842.9 19034.4 19236.4 19318.9 9580.4 10924.2 11146.8 11344.5 11859.9 13733.7 14393.4 14460.3 14497.8 14527.8 10786.7 10787.2 11146.2 11380.9 11449.1 10396.7 10604.9 11037.9 11085.9 11226.5 9790.4 9807.0 9829.8 9879.3 9901.8 6579.7 6821.6 7329.9 7393.4 7448.2 7146.9 7188.3 7474.2 7508.1 7508.1 12:24:36 INFO - PID 2380 | 12:24:36 INFO - PID 2380 | [#4] prototype.html Cycles:180 Average:5517.57 Median:6072.06 stddev:1681.96 (27.7%) stddev-sans-first:1642.89 12:24:36 INFO - PID 2380 | Values: 437.7 468.7 478.9 498.7 504.8 5592.9 6377.0 6430.4 6460.8 6518.0 6070.0 6312.3 6353.9 6356.1 6386.8 3959.9 4190.8 4771.3 4787.6 4800.6 3660.6 4263.8 4821.2 4835.8 4845.8 3601.8 4361.6 4534.2 4768.1 4813.9 4560.3 4746.2 4747.2 4777.2 4805.6 4191.3 4211.0 4220.2 4228.8 4229.8 4793.9 5411.9 5481.7 6139.0 6288.1 5189.8 5723.5 5723.6 5730.9 5789.8 6846.7 6865.2 6890.3 6924.3 6925.9 4837.0 4847.5 4853.7 4866.2 4871.4 6033.0 7069.8 7193.9 7220.7 7309.6 4937.5 4959.9 4963.5 4996.5 5026.1 5052.4 5060.3 5068.0 5093.5 5097.2 4363.3 5280.4 5696.7 5704.7 5736.3 6903.1 6915.4 6923.5 6935.7 6970.7 1572.3 1718.3 1721.3 1740.9 1748.8 1171.6 1177.1 1179.0 1184.8 1185.2 5693.7 6321.8 6439.9 6441.6 6500.4 5723.7 6812.9 7017.6 7048.7 7067.3 6186.6 6369.5 6375.6 6392.3 6395.9 3652.3 3679.9 3681.9 3683.2 3707.6 6149.4 6931.8 7247.4 7251.5 7278.6 6097.1 6432.1 7247.6 7257.6 7283.7 5907.6 6788.3 6945.9 7219.6 7241.0 6611.5 6988.5 7034.2 7070.9 7088.3 5496.4 6601.7 6976.9 6995.4 7070.7 6275.2 7169.5 7219.0 7220.9 7275.3 4090.8 4332.8 4821.5 4920.5 4965.9 6546.6 6565.2 7062.3 7131.8 7165.3 5621.9 5956.7 6074.1 6713.4 6714.6 5997.4 6432.3 6689.5 6810.4 6841.8 5695.1 6650.4 6702.3 6703.5 6761.9 5252.0 6851.5 6851.8 6851.8 6854.3 5559.6 6573.4 6628.0 6643.2 6670.4 12:24:36 INFO - PID 2380 | 12:24:36 INFO - PID 2380 | [#5] yui.html Cycles:180 Average:26405.45 Median:18135.09 stddev:40467.76 (223.1%) stddev-sans-first:40535.59 12:24:36 INFO - PID 2380 | Values: 794.6 823.4 832.1 841.7 869.6 17024.0 17385.5 17416.8 17432.7 19238.3 18884.9 19391.7 19481.6 19533.0 19755.5 940.1 974.2 1016.7 1065.6 1073.1 1064.3 1065.0 1065.4 1066.8 1074.1 1119.5 1127.6 1130.3 1131.4 1134.4 1120.7 1124.2 1125.3 1128.0 1142.4 744.1 771.0 831.6 837.8 853.7 18121.8 18148.4 18188.9 18368.3 18369.4 16600.3 17201.5 17569.2 17933.2 18687.3 15061.2 15100.9 15102.2 15130.8 15243.2 3660.9 3697.2 3721.3 3727.2 3730.5 158759.0 159920.0 160184.0 162494.0 165234.0 12915.6 13355.1 14137.6 14254.6 14280.4 27371.3 27549.4 27847.6 28151.2 28187.6 7980.4 7989.0 8098.2 8122.1 8123.1 3636.9 3758.5 3782.8 4000.4 4096.3 4824.8 5372.6 5404.1 5546.6 5565.9 1100.1 1131.1 1134.0 1163.2 1164.1 651.5 669.3 674.3 690.5 694.1 11362.7 12196.8 12683.4 12734.2 12788.6 636.0 645.3 648.6 667.9 694.7 5624.1 5648.2 5657.0 5698.8 5940.2 130476.0 135293.3 137299.3 137410.0 142157.0 119083.0 120667.0 121766.4 126327.0 131105.0 106673.0 106994.5 107417.9 107549.5 110816.0 11290.5 11320.3 11338.3 11371.4 11803.2 39702.8 44798.8 45098.9 50446.5 51359.0 67719.3 68165.0 71690.0 72244.3 72304.0 14877.3 15133.2 15340.2 15395.6 15498.5 13604.6 16014.3 16825.7 20345.8 21070.8 18450.4 18486.7 18678.4 18758.5 18864.0 18268.6 18818.4 18910.1 19147.0 19328.5 17702.5 18205.9 19082.8 19130.8 19291.3 10980.7 12248.8 13093.0 13317.1 13718.6 13198.9 13941.6 14097.0 14184.3 14846.1 12:24:36 INFO - PID 2380 | -------- Summary: end -------- 12:24:36 INFO - PID 2380 | 12:24:36 INFO - TEST-INFO | 2380: exit 0 12:24:36 INFO - TEST-OK | dromaeo_css | took 1118226ms 12:24:36 INFO - TEST-START | kraken 12:24:36 INFO - operating with platform_type : mac_ 12:24:36 INFO - Initialising browser for kraken test... 12:24:36 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56959/getInfo.html -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmphAJ3nU/profile 12:24:36 INFO - TEST-INFO | started process 2386 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56959/getInfo.html -foreground) 12:24:41 INFO - TEST-INFO | 2386: exit 0 12:24:41 INFO - Browser initialized. 12:24:41 INFO - Running cycle 1/1 for kraken test... 12:24:41 INFO - TEST-INFO | started process 2392 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmphAJ3nU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:24:43 INFO - PID 2392 | 2017-06-21 12:24:43.382 plugin-container[2393:31977] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 12:24:43 INFO - PID 2392 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:24:43 INFO - PID 2392 | 2017-06-21 12:24:43.383 plugin-container[2393:31977] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 12:24:43 INFO - PID 2392 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:24:43 INFO - PID 2392 | 2017-06-21 12:24:43.383 plugin-container[2393:31977] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:24:43 INFO - PID 2392 | 2017-06-21 12:24:43.947 plugin-container[2394:32029] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 12:24:43 INFO - PID 2392 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:24:43 INFO - PID 2392 | 2017-06-21 12:24:43.947 plugin-container[2394:32029] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 12:24:43 INFO - PID 2392 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:24:43 INFO - PID 2392 | 2017-06-21 12:24:43.947 plugin-container[2394:32029] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:24:53 INFO - PID 2392 | RSS: Main: 249278464 12:24:53 INFO - PID 2392 | 12:24:55 INFO - PID 2392 | Cycle 1(1): loaded http://localhost:56959/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56959/tests/kraken/driver.html?testName=audio-beat-detection) 12:24:56 INFO - PID 2392 | RSS: Main: 260468736 12:24:56 INFO - PID 2392 | 12:24:59 INFO - PID 2392 | Cycle 1(1): loaded http://localhost:56959/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56959/tests/kraken/driver.html?testName=audio-dft) 12:25:00 INFO - PID 2392 | RSS: Main: 269623296 12:25:00 INFO - PID 2392 | 12:25:03 INFO - PID 2392 | Cycle 1(1): loaded http://localhost:56959/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56959/tests/kraken/driver.html?testName=audio-fft) 12:25:04 INFO - PID 2392 | RSS: Main: 271749120 12:25:04 INFO - PID 2392 | 12:25:06 INFO - PID 2392 | Cycle 1(1): loaded http://localhost:56959/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56959/tests/kraken/driver.html?testName=audio-oscillator) 12:25:06 INFO - PID 2392 | RSS: Main: 274149376 12:25:06 INFO - PID 2392 | 12:25:08 INFO - PID 2392 | Cycle 1(1): loaded http://localhost:56959/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56959/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:25:09 INFO - PID 2392 | RSS: Main: 274509824 12:25:09 INFO - PID 2392 | 12:25:12 INFO - PID 2392 | Cycle 1(1): loaded http://localhost:56959/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56959/tests/kraken/driver.html?testName=imaging-darkroom) 12:25:12 INFO - PID 2392 | RSS: Main: 281518080 12:25:12 INFO - PID 2392 | 12:25:15 INFO - PID 2392 | Cycle 1(1): loaded http://localhost:56959/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56959/tests/kraken/driver.html?testName=imaging-desaturate) 12:25:16 INFO - PID 2392 | RSS: Main: 282968064 12:25:16 INFO - PID 2392 | 12:25:19 INFO - PID 2392 | Cycle 1(1): loaded http://localhost:56959/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56959/tests/kraken/driver.html?testName=json-parse-financial) 12:25:19 INFO - PID 2392 | RSS: Main: 282292224 12:25:19 INFO - PID 2392 | 12:25:21 INFO - PID 2392 | Cycle 1(1): loaded http://localhost:56959/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56959/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:25:21 INFO - PID 2392 | RSS: Main: 282181632 12:25:21 INFO - PID 2392 | 12:25:23 INFO - PID 2392 | Cycle 1(1): loaded http://localhost:56959/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56959/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:25:24 INFO - PID 2392 | RSS: Main: 282152960 12:25:24 INFO - PID 2392 | 12:25:26 INFO - PID 2392 | Cycle 1(1): loaded http://localhost:56959/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56959/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:25:27 INFO - PID 2392 | RSS: Main: 281714688 12:25:27 INFO - PID 2392 | 12:25:29 INFO - PID 2392 | Cycle 1(1): loaded http://localhost:56959/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56959/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:25:30 INFO - PID 2392 | RSS: Main: 281829376 12:25:30 INFO - PID 2392 | 12:25:33 INFO - PID 2392 | Cycle 1(1): loaded http://localhost:56959/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56959/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:25:34 INFO - PID 2392 | RSS: Main: 282058752 12:25:34 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | Cycle 1(1): loaded http://localhost:56959/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56959/tests/kraken/driver.html?testName=ai-astar) 12:25:36 INFO - PID 2392 | RSS: Main: 283877376 12:25:36 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | __start_tp_report 12:25:36 INFO - PID 2392 | _x_x_mozilla_page_load 12:25:36 INFO - PID 2392 | _x_x_mozilla_page_load_details 12:25:36 INFO - PID 2392 | |i|pagename|runs| 12:25:36 INFO - PID 2392 | |0;ai-astar;106;111;115;112;111;112;112;117;112;114 12:25:36 INFO - PID 2392 | |1;audio-beat-detection;137;111;154;152;97;164;166;146;168;108 12:25:36 INFO - PID 2392 | |2;audio-dft;205;199;196;202;226;222;225;202;204;195 12:25:36 INFO - PID 2392 | |3;audio-fft;69;87;80;135;54;58;69;65;105;86 12:25:36 INFO - PID 2392 | |4;audio-oscillator;70;70;111;69;77;70;113;68;72;112 12:25:36 INFO - PID 2392 | |5;imaging-gaussian-blur;88;88;89;89;88;90;90;89;90;91 12:25:36 INFO - PID 2392 | |6;imaging-darkroom;96;97;96;97;128;98;98;96;98;97 12:25:36 INFO - PID 2392 | |7;imaging-desaturate;92;93;91;91;91;91;120;148;92;89 12:25:36 INFO - PID 2392 | |8;json-parse-financial;50;52;54;52;51;57;52;55;52;52 12:25:36 INFO - PID 2392 | |9;json-stringify-tinderbox;54;54;58;53;52;60;54;56;54;53 12:25:36 INFO - PID 2392 | |10;stanford-crypto-aes;81;88;108;103;83;79;80;82;98;104 12:25:36 INFO - PID 2392 | |11;stanford-crypto-ccm;149;149;148;144;132;140;137;144;158;173 12:25:36 INFO - PID 2392 | |12;stanford-crypto-pbkdf2;175;222;234;210;175;251;223;206;175;202 12:25:36 INFO - PID 2392 | |13;stanford-crypto-sha256-iterative;66;61;80;97;93;93;85;62;69;86 12:25:36 INFO - PID 2392 | __end_tp_report 12:25:36 INFO - PID 2392 | __start_cc_report 12:25:36 INFO - PID 2392 | _x_x_mozilla_cycle_collect,1097 12:25:36 INFO - PID 2392 | __end_cc_report 12:25:36 INFO - PID 2392 | __startTimestamp1498073136665__endTimestamp 12:25:36 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | ------- Summary: start ------- 12:25:36 INFO - PID 2392 | Number of tests: 14 12:25:36 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | [#0] ai-astar Cycles:10 Average:112.20 Median:112.00 stddev:2.90 (2.6%) stddev-sans-first:2.03 12:25:36 INFO - PID 2392 | Values: 106.0 111.0 115.0 112.0 111.0 112.0 112.0 117.0 112.0 114.0 12:25:36 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:159.00 stddev:26.11 (16.4%) stddev-sans-first:27.66 12:25:36 INFO - PID 2392 | Values: 137.0 111.0 154.0 152.0 97.0 164.0 166.0 146.0 168.0 108.0 12:25:36 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | [#2] audio-dft Cycles:10 Average:207.60 Median:204.50 stddev:12.01 (5.9%) stddev-sans-first:12.70 12:25:36 INFO - PID 2392 | Values: 205.0 199.0 196.0 202.0 226.0 222.0 225.0 202.0 204.0 195.0 12:25:36 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | [#3] audio-fft Cycles:10 Average:80.80 Median:69.00 stddev:24.39 (35.4%) stddev-sans-first:25.50 12:25:36 INFO - PID 2392 | Values: 69.0 87.0 80.0 135.0 54.0 58.0 69.0 65.0 105.0 86.0 12:25:36 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | [#4] audio-oscillator Cycles:10 Average:83.20 Median:70.00 stddev:20.03 (28.6%) stddev-sans-first:20.66 12:25:36 INFO - PID 2392 | Values: 70.0 70.0 111.0 69.0 77.0 70.0 113.0 68.0 72.0 112.0 12:25:36 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | [#5] imaging-gaussian-blur Cycles:10 Average:89.20 Median:89.50 stddev:1.03 (1.2%) stddev-sans-first:1.00 12:25:36 INFO - PID 2392 | Values: 88.0 88.0 89.0 89.0 88.0 90.0 90.0 89.0 90.0 91.0 12:25:36 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | [#6] imaging-darkroom Cycles:10 Average:100.10 Median:97.00 stddev:9.84 (10.1%) stddev-sans-first:10.32 12:25:36 INFO - PID 2392 | Values: 96.0 97.0 96.0 97.0 128.0 98.0 98.0 96.0 98.0 97.0 12:25:36 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | [#7] imaging-desaturate Cycles:10 Average:99.80 Median:91.00 stddev:19.22 (21.1%) stddev-sans-first:20.18 12:25:36 INFO - PID 2392 | Values: 92.0 93.0 91.0 91.0 91.0 91.0 120.0 148.0 92.0 89.0 12:25:36 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:52.00 stddev:2.06 (4.0%) stddev-sans-first:1.94 12:25:36 INFO - PID 2392 | Values: 50.0 52.0 54.0 52.0 51.0 57.0 52.0 55.0 52.0 52.0 12:25:36 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | [#9] json-stringify-tinderbox Cycles:10 Average:54.80 Median:54.00 stddev:2.49 (4.6%) stddev-sans-first:2.62 12:25:36 INFO - PID 2392 | Values: 54.0 54.0 58.0 53.0 52.0 60.0 54.0 56.0 54.0 53.0 12:25:36 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:81.50 stddev:11.39 (14.0%) stddev-sans-first:11.54 12:25:36 INFO - PID 2392 | Values: 81.0 88.0 108.0 103.0 83.0 79.0 80.0 82.0 98.0 104.0 12:25:36 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | [#11] stanford-crypto-ccm Cycles:10 Average:147.40 Median:148.50 stddev:11.53 (7.8%) stddev-sans-first:12.21 12:25:36 INFO - PID 2392 | Values: 149.0 149.0 148.0 144.0 132.0 140.0 137.0 144.0 158.0 173.0 12:25:36 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:207.30 Median:216.00 stddev:26.36 (12.2%) stddev-sans-first:25.23 12:25:36 INFO - PID 2392 | Values: 175.0 222.0 234.0 210.0 175.0 251.0 223.0 206.0 175.0 202.0 12:25:36 INFO - PID 2392 | 12:25:36 INFO - PID 2392 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.20 Median:85.50 stddev:13.68 (16.0%) stddev-sans-first:13.65 12:25:36 INFO - PID 2392 | Values: 66.0 61.0 80.0 97.0 93.0 93.0 85.0 62.0 69.0 86.0 12:25:36 INFO - PID 2392 | -------- Summary: end -------- 12:25:36 INFO - PID 2392 | 12:25:37 INFO - TEST-INFO | 2392: exit 0 12:25:37 INFO - TEST-OK | kraken | took 60614ms 12:25:37 INFO - SUITE-END | took 1178s 12:25:37 INFO - Completed test suite (00:19:38) 12:25:37 INFO - javascript benchmark 12:25:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8636.516072038574, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3584.444411116278, 3640.672339489445, 3658.2866341063486, 3678.896331100671, 3706.573744019437, 5312.78748850046, 5711.314642242926, 6110.908336374958, 6455.2576453707725, 6480.157579514668, 6388.329225431321, 6521.119648847404, 6580.111684922534, 6584.604923704581, 6599.769008084706, 5708.828735137931, 5738.880918220949, 5761.222234998285, 5840.737166827482, 5918.319393269764, 5792.65244085352, 5910.734016969225, 5926.674032928187, 5939.495142912858, 5966.105084237356, 5637.069883469223, 5699.2590963174935, 5740.052891272935, 5772.30732312121, 5796.62321949072, 5674.375818659944, 5693.3452652945025, 5714.0, 5775.075987841925, 5811.912821307684, 5591.329040515124, 5647.265855438808, 5714.371419143891, 5762.337331206881, 5768.192453056576, 6515.674216289167, 6524.119243902107, 6564.1138446309615, 6585.835354116138, 6606.669666516703, 5910.7931222407005, 6907.861842763167, 7149.392301654366, 7158.606276654782, 7210.531315464478, 7163.247858974784, 7168.60572668503, 7195.748148814819, 7240.782776516713, 7246.021787058785, 6600.5049621278595, 6646.368594983469, 6703.430208432338, 6720.865582688417, 6723.899141512783, 7189.0, 7424.554526728413, 7425.888611670829, 7437.665305061177, 7443.292887175709, 6944.097267355211, 6950.374466298057, 6980.371766541036, 6980.825479363057, 6981.30186981308, 7048.682809273595, 7122.394596459359, 7180.7845764627145, 7258.3104605062435, 7285.198628150899, 7089.184743754431, 7103.786886393416, 7120.857582848314, 7121.359077683033, 7123.7506687265695, 5902.61632993854, 6887.586744795329, 7009.7897063088185, 7103.218645948941, 7123.038389817309, 5492.5605951523785, 5496.5053145217125, 5533.391326954032, 5542.501174894279, 5573.470520300565, 4882.096812089775, 4952.653314267966, 4968.826091086795, 4994.350734404504, 5011.724355160502, 6682.3317668232785, 6710.932890671128, 6713.966430167818, 6715.059891615178, 6743.15710536183, 5912.379200184005, 7245.492815502864, 7256.818579535448, 7258.056452661226, 7264.709411623581, 6459.806205813833, 6544.411003009657, 6682.131322928086, 6705.195376554844, 6714.597124172566, 6172.9074063889975, 6195.349488303754, 6216.751329946752, 6273.7176827042895, 6313.84215394628, 7293.562386256842, 7318.597477138595, 7323.377512911462, 7329.083864516935, 7348.77953661391, 6363.3000369958345, 6711.798646040626, 7134.85730285381, 7152.785416437515, 7175.497715160098, 7278.818029549304, 7297.744578939712, 7299.306565876233, 7303.52527085759, 7319.4144468443465, 5697.971510142423, 6878.896816547655, 6971.407430368476, 6974.0, 7001.684924178322, 5782.595218334676, 7192.892106618509, 7217.819554511179, 7259.564426134449, 7299.197088320174, 7047.40097091753, 7058.435325174077, 7064.505484616233, 7083.574985500886, 7098.077249957679, 6920.688569014507, 6940.444764418733, 7011.1586609607175, 7037.225905150532, 7044.6125463100025, 6714.798556043326, 6834.214065382345, 6838.04267402574, 6838.760643377458, 6841.62371069596, 6863.691133899087, 6880.65596720152, 6896.620685862325, 6971.058907047483, 6996.930030699831, 6835.4189893859575, 6865.313468653095, 6870.553414028072, 6904.482163837632, 6936.167659880644], "value": 6451.11484408447, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [896.1531352871536, 903.6349004741176, 957.3155194036262, 982.3614650477184, 983.0, 37576.624233757655, 38808.0, 38819.22361552767, 38870.61129388705, 38894.0, 38955.026124346834, 39716.0, 39778.80110599443, 39839.00402489939, 39907.40138897919, 8510.319174466043, 8578.185072418142, 8646.221840034395, 8682.218600325969, 8693.782655433633, 8438.662453501853, 8533.018702849158, 8543.102974187714, 8551.273141782956, 8581.442206256605, 8510.063892971768, 8556.74329770108, 8559.27246184072, 8564.528950907697, 8598.140185981383, 8564.0, 8573.485590864568, 8591.226789588905, 8592.312614990817, 8595.785105372353, 7501.912222727674, 7976.800579985489, 8312.709055183039, 8406.201410865959, 8408.537530435824, 36849.26301473982, 36989.0, 37007.0, 37110.25779484422, 37132.814335928146, 3559.2169722660974, 3565.0196196046036, 3565.4117070683305, 3601.2077342984503, 3601.513795637608, 30234.546481802572, 30581.541276880864, 30645.23386915345, 30670.386592268253, 30679.0, 2607.4002979314864, 3045.649750278702, 3130.5930229070077, 3159.5418664293547, 3191.1543440988157, 282983.0, 284756.9999999979, 287489.0, 292371.0, 292962.0, 40404.0, 47504.04991900142, 47751.5224847754, 47788.0, 48622.78443038881, 45901.31148032782, 51188.74405627948, 51792.48207517952, 51827.48172518302, 51959.0, 14522.983391162728, 14550.708985820223, 14631.634209144857, 14665.780013299809, 14667.046641968238, 11938.94030529859, 12161.878381216075, 12174.878251217551, 12181.817272740916, 12195.268283902817, 9830.705078847646, 11756.35340056288, 11832.408379580986, 11882.702932426586, 11935.880641193477, 4790.856274311776, 5358.892822143535, 5428.538574221236, 5434.83695489136, 5501.037318469252, 957.7557722780647, 1010.661428421487, 1025.9743506412399, 1076.0853274716428, 1077.8059949209219, 18947.33684321042, 19497.610047799248, 20035.09315683428, 22558.436039098826, 23803.285901422983, 1113.4154568851418, 1128.6162704680285, 1133.484264659578, 1135.5684839760838, 1140.7547377313917, 10144.644937427154, 10537.63118290856, 10869.0, 11098.502836861802, 12022.879771202175, 294335.0, 341285.0, 341743.0, 341997.0, 342218.0, 52971.73514132405, 53563.19655205175, 53679.926401472534, 53691.19463208055, 53732.46267537353, 1470.8235011798652, 1546.373718643951, 1568.8195857476537, 1577.7712231726334, 1580.4231455518652, 21574.980559488016, 21973.13228375606, 22724.091036359023, 22726.0, 23392.415189620395, 36928.81535592305, 37049.0, 37140.628593713715, 37177.0, 37381.065473363386, 271397.0, 272701.0, 275328.0, 275815.0, 277232.0, 29766.80932762665, 30396.84801575978, 30598.541021884244, 33693.32613347768, 34020.65979340274, 39005.02487437837, 42436.0, 42437.0, 44608.10783784371, 44838.0, 27456.588151177348, 27883.302917427227, 28363.0, 29644.01487877412, 30925.69074309228, 29263.268418289714, 29709.257268567595, 30043.549346760254, 30449.39101217919, 30502.542461862646, 36762.0, 36842.0, 36874.8156259217, 36882.0, 36993.07517312089, 29182.27044323909, 29846.552301715925, 30030.699693003666, 30192.0, 30519.084427467213, 22021.449463763533, 23876.806159691598, 24108.156214532406, 24192.87903560471, 24200.515989679756], "value": 17185.35882572906, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.0872386409578, 546.8687514996401, 553.9639923404978, 565.9632123911948, 572.6792995922282, 22899.427514312152, 23410.0, 23756.40608984774, 23764.999999999956, 23795.64306535399, 19242.51893702658, 26790.866045669794, 27027.729722702767, 27052.72947270527, 27078.32304192391, 6755.459563234942, 6899.517033807636, 6903.482238832083, 6918.861622767567, 6920.861582768342, 6808.795736127924, 6814.250432452426, 6837.1795384553925, 6857.417119544844, 6863.862722745542, 4967.528084831935, 6229.968850155743, 6477.0, 6621.536492445531, 6626.237982631987, 6533.085368048477, 6636.270010298887, 6639.867202655944, 6642.202935647705, 6647.567908086007, 6153.846153846145, 6164.660943648098, 6179.845503862394, 6183.258009038899, 6192.535559832985, 31339.686603133905, 31559.0, 31614.0, 31636.683633163833, 32123.83938080318, 2185.27885797688, 2241.5068684889297, 2248.2580748353166, 2249.561335539554, 2250.0999600159903, 19212.903935480233, 19649.31227407034, 19708.80291197084, 19733.802661973485, 19839.702404463944, 2217.889105544716, 2253.3352660965056, 2282.315305408371, 2301.873396963166, 2304.631258998569, 188596.0, 190730.0, 192902.0, 193249.9999999986, 195123.0, 13606.0, 16226.0, 16247.59381015484, 16403.507894763177, 16488.505344839676, 20072.397828065183, 22773.08907643709, 22827.543449130924, 22882.771172288398, 22931.0, 10193.388396696222, 10321.948390258001, 10338.793224135476, 10409.895901041044, 10421.166306695597, 9295.953520232355, 9303.906960930304, 9357.812843743222, 9375.578098985561, 9450.669226577036, 9612.423254604746, 9751.707448776406, 9790.902090979142, 9816.460094694718, 9829.115379615869, 2321.025169428844, 2350.471143992588, 2411.0, 2591.248537924019, 2744.286485513741, 949.7910459698855, 952.4427560937778, 971.708487453761, 982.7837875667342, 1026.650938680852, 21286.36140915775, 21527.784722152577, 27511.58732619012, 29419.705802942124, 29434.558481622793, 1033.7105610429091, 1049.4333060147587, 1054.2988912373187, 1061.177587369779, 1064.499685147979, 11887.940560297144, 12044.855738704984, 12142.0, 12177.939110304393, 12515.624531264077, 514140.0, 523663.0, 528714.0, 568104.0, 576180.5524320701, 165353.0, 166172.0, 166189.0, 167649.0, 169852.0, 1202.05038019965, 1229.6372570091844, 1248.182440501473, 1261.3188878005958, 1264.6522206393315, 23112.191073313024, 23628.763712362655, 24563.017479301343, 24692.876535617925, 24737.876310619053, 67229.66385168042, 68230.0, 72966.0, 74200.0, 76633.61683191548, 88650.0, 92232.0, 92566.53716731373, 93498.13003740023, 94926.52536737273, 12298.262104273772, 12438.37808109609, 12445.25328480294, 12537.686557836127, 12604.936975315064, 16620.0, 17917.73123403124, 18778.248870045045, 19097.331593394163, 19166.8083319165, 11499.597514086967, 14047.508337208148, 15264.23678816077, 15616.843831561539, 15758.921205393899, 13476.460941562227, 13964.30178491051, 14090.968148155453, 14258.643533911736, 14601.904857135969, 16249.67500650004, 16405.83594164043, 16412.671746564763, 16463.91768041152, 16513.504594862174, 12478.688032799253, 12549.121561490609, 12618.55835045769, 12625.0, 12715.936420318209, 11860.466279017637, 14561.0, 15217.847821521644, 15502.147381894105, 15761.0], "value": 12276.633634925864, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.898307626928, 1549.5273941447854, 1551.4957638767403, 1553.5028790786946, 1560.0795530636922, 11267.492962816672, 15738.606534836636, 15757.448489302848, 15785.342539033409, 15812.20938953052, 12969.93515032426, 13358.532451364204, 13437.260950647735, 14276.928615356934, 14596.489122880701, 2882.8126171798817, 2945.631796025497, 2948.9557656635166, 2956.896508622199, 2959.2601849537614, 2865.1404578626434, 2893.291143669793, 2897.4350001749667, 2902.3614804742924, 2906.128161551526, 2857.4427986542632, 2874.985625071882, 2900.2169414258137, 2901.332693480501, 2901.4777340078776, 2836.673782515006, 2837.0779496663563, 2854.9000784972636, 2858.857057147134, 2859.1851322373022, 2368.2421625079983, 2821.887124515017, 2833.0367674990603, 2833.263351528597, 2837.4750671125908, 14995.925020374938, 15282.465113720966, 15342.925995180341, 15358.923205383902, 15364.154971476564, 6736.2590115087305, 8645.438046526955, 8653.134686531359, 8655.6970506032, 8659.870101948476, 4928.310036594879, 5182.559482443997, 5330.173823057432, 5357.464253574612, 5434.157705555605, 1430.763923952557, 1455.6317061961722, 1483.2435457916433, 1493.5071426429251, 1537.6847746212, 20890.5821883563, 22840.885795570917, 24208.757912420828, 24295.392615184763, 24433.022679092814, 7657.463977521631, 7853.625615517261, 7854.6858125674335, 8020.19798020205, 8041.356691464669, 15304.693906122038, 18879.150438230037, 19119.71320430195, 19188.520286992938, 19197.8080219196, 6947.7220911162985, 7040.2607726189035, 7905.881411778828, 8314.0, 8399.286060684788, 6481.0, 7547.849043019219, 7572.167061623217, 7651.5409075455655, 7820.413468989848, 4664.043871006435, 5453.400125986138, 5560.638558493685, 5632.436756324335, 5683.829485115453, 1359.714459963399, 1495.2673190136754, 1633.338497908349, 1645.1210381831795, 1655.9751603725954, 860.6815478273079, 861.5821326656567, 884.2395539835736, 885.1104639836923, 905.6015353244551, 6852.6231057292325, 7070.116235470567, 7403.370713489306, 7657.0, 7787.844243115106, 714.7284032067781, 737.2664199121908, 841.7979684875709, 905.3119629081945, 959.9184069354044, 4932.8766780830765, 4961.231009193581, 5223.0, 5354.571634269249, 5473.917891231635, 19405.385226522343, 20974.951252437317, 21219.575608487743, 21428.357149285548, 21550.922453877243, 19942.20231190765, 21159.25942592002, 21185.046672899447, 21242.044108015176, 21288.467788305417, 10262.897371026344, 10288.228382871164, 10319.74200644975, 10323.0, 10363.63727269542, 7481.625918704151, 8349.33205343559, 8469.237768600855, 8685.956570217108, 9227.215686666708, 17764.644707106043, 18194.181261842983, 18372.632547349247, 18897.622047558696, 19038.33365832189, 17871.642567148843, 18842.905785470986, 19034.428967131007, 19236.422907312804, 19318.93745843992, 9580.425174489554, 10924.235303528676, 11146.832797507881, 11344.489497972447, 11859.940700296444, 13733.725325493633, 14393.352299146354, 14460.349284282021, 14497.78253326222, 14527.782083268548, 10786.676399708022, 10787.244892857425, 11146.164037697043, 11380.886191137983, 11449.141314401286, 10396.740081498023, 10604.946975265382, 11037.944810275898, 11085.94457027742, 11226.494807733836, 9790.412575245507, 9807.0, 9829.754256143367, 9879.259055571005, 9901.752456188653, 6579.736810527525, 6821.556598821061, 7329.853402931805, 7393.408527317909, 7448.217937116741, 7146.9285307146265, 7188.317109874553, 7474.177840437439, 7508.0990281166605, 7508.0990281166605], "value": 6613.6815369466885, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [437.6586262715081, 468.6531966344906, 478.8850675837798, 498.6683855236268, 504.8132191089296, 5592.944070559293, 6377.011563207699, 6430.3569643035735, 6460.838479038026, 6517.989711594699, 6070.0, 6312.33720459352, 6353.868516881209, 6356.078368636545, 6386.840328991778, 3959.9406008909746, 4190.769507677077, 4771.260454629521, 4787.593054590364, 4800.591949684281, 3660.5790334111653, 4263.82744745196, 4821.180399332122, 4835.830745923893, 4845.830395936143, 3601.8019008954498, 4361.60745532902, 4534.183846907556, 4768.141734487791, 4813.855584332476, 4560.312684306795, 4746.240601503778, 4747.192977193886, 4777.163996300668, 4805.591524720403, 4191.3293872980175, 4210.9578904210875, 4220.177065472234, 4228.767417792051, 4229.788510574459, 4793.921367692276, 5411.917616476681, 5481.725913704299, 6139.0, 6288.126701806137, 5189.806344540777, 5723.484886360206, 5723.599348045638, 5730.885382292331, 5789.826305210809, 6846.726130954806, 6865.244823069458, 6890.276520965276, 6924.3421874921805, 6925.930740692579, 4836.951630483721, 4847.521505940686, 4853.733044682541, 4866.172750632401, 4871.368091689288, 6032.9698351508405, 7069.752558660422, 7193.928060719378, 7220.67506962188, 7309.634518274065, 4937.530934561211, 4959.876003099951, 4963.478834722375, 4996.525330093636, 5026.070177017264, 5052.418971818288, 5060.316857224301, 5068.037072956126, 5093.516115968977, 5097.235414687826, 4363.2800587903275, 5280.445553216934, 5696.658200507983, 5704.657720536781, 5736.254286942671, 6903.068085808452, 6915.4121899638085, 6923.5460553283365, 6935.6878940447805, 6970.721171153097, 1572.2846105022188, 1718.2954988454924, 1721.2942693495606, 1740.85202757767, 1748.8163742807076, 1171.625079974417, 1177.140687298277, 1179.0, 1184.7808155491261, 1185.158537438411, 5693.658380497183, 6321.810345689637, 6439.9356006440275, 6441.613503189824, 6500.414962653384, 5723.656580605177, 6812.8978065328065, 7017.614031228331, 7048.718051277891, 7067.339175293801, 6186.628802271878, 6369.490440764727, 6375.6174629522375, 6392.264889537762, 6395.904061439082, 3652.342578335925, 3679.8712045078296, 3681.8711345102793, 3683.189698266377, 3707.573629032696, 6149.41580549847, 6931.826704332331, 7247.420206383476, 7251.5286506376915, 7278.599677017814, 6097.085437184458, 6432.070378886316, 7247.637618119178, 7257.564546127249, 7283.70865165398, 5907.556933230025, 6788.287229840895, 6945.93054069463, 7219.639018049076, 7240.96379518099, 6611.504137189826, 6988.510804243654, 7034.226235114031, 7070.858582828418, 7088.326608972139, 5496.367917689517, 6601.669916504059, 6976.930230697628, 6995.440364770704, 7070.681819318246, 6275.246970363403, 7169.530246299391, 7219.0, 7220.89168662481, 7275.34521893011, 4090.754554726726, 4332.8266869326135, 4821.54195351441, 4920.507949205051, 4965.875853103556, 6546.574472659262, 6565.222604108545, 7062.329078737512, 7131.786046418616, 7165.319294666998, 5621.8594535136945, 5956.672383018976, 6074.119252708421, 6713.429358504386, 6714.597124172566, 5997.430244126801, 6432.292447830829, 6689.531732778659, 6810.387065164159, 6841.828954276183, 5695.145728140812, 6650.401463868275, 6702.262751097278, 6703.530752847254, 6761.898571521333, 5251.973740131275, 6851.486138539729, 6851.794446166623, 6851.828704282433, 6854.34883686049, 5559.555235581225, 6573.408393244631, 6628.0, 6643.202815661958, 6670.433013193933], "value": 5023.772055107182, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.6146119132221, 823.4318320358952, 832.1096426823298, 841.6927821345204, 869.5826003518307, 17024.0, 17385.47843564692, 17416.82583174168, 17432.651346973085, 19238.32665856692, 18884.905575472138, 19391.70912436311, 19481.610367792637, 19533.0, 19755.506112347197, 940.0975063938627, 974.1914211204684, 1016.7356487313315, 1065.6483360491038, 1073.1468482556386, 1064.2603390643505, 1065.046783129099, 1065.3874022437092, 1066.802641511319, 1074.1138560687425, 1119.4850368830348, 1127.560251501915, 1130.3331034689531, 1131.3664347965125, 1134.4044376702216, 1120.6918097523164, 1124.173732306754, 1125.302312566206, 1127.9718007049848, 1142.3716955674388, 744.118219909409, 770.9730159444449, 831.6465502161557, 837.8324335132937, 853.714005808055, 18121.818781812144, 18148.364807231814, 18188.90905545464, 18368.26526938921, 18369.357072502396, 16600.25298861542, 17201.483955481355, 17569.20938557768, 17933.19300631475, 18687.252509899587, 15061.17163556004, 15100.924495377452, 15102.169380684058, 15130.848691513054, 15243.16162611056, 3660.871869484555, 3697.242065376591, 3721.3115573618697, 3727.235916637083, 3730.5336832895887, 158759.0, 159920.0, 160184.0, 162494.0, 165234.0, 12915.579286278502, 13355.131916425595, 14137.575872723835, 14254.643633909236, 14280.357383917748, 27371.315717106834, 27549.449011019668, 27847.582286265722, 28151.155465336073, 28187.57718634222, 7980.441369104223, 7989.0, 8098.190180981855, 8122.147174546588, 8123.147069557729, 3636.9272614547563, 3758.4550240215017, 3782.8486860525823, 4000.4199391088127, 4096.262672718939, 4824.831130910401, 5372.5970552207955, 5404.108322126884, 5546.639468434539, 5565.888682226333, 1100.1308965916996, 1131.0951239008925, 1133.9943300283446, 1163.2322667039714, 1164.1210885781181, 651.5406638319973, 669.2872091222832, 674.2785219814751, 690.547691262224, 694.1254019934919, 11362.659120226406, 12196.81704774429, 12683.433963773034, 12734.172278801849, 12788.552400665933, 635.9968200159062, 645.2643985856128, 648.5946283572767, 667.8564108716649, 694.6526736631685, 5624.128260119688, 5648.181013752983, 5656.971715141398, 5698.800541981011, 5940.150073242949, 130476.0, 135293.32353338366, 137299.31350343185, 137410.0, 142157.0, 119083.0, 120667.0, 121766.3911680436, 126327.0, 131105.0, 106673.0, 106994.46502767436, 107417.92582074236, 107549.46225268823, 110816.0, 11290.548378064785, 11320.26418282809, 11338.263012904135, 11371.374574398487, 11803.23278986863, 39702.801485992386, 44798.776006121065, 45098.87252818706, 50446.49553504565, 51358.97282054263, 67719.30701732496, 68165.0, 71690.0, 72244.27755722376, 72304.0, 14877.330520126838, 15133.243337832844, 15340.232988350761, 15395.61510962235, 15498.5350439487, 13604.591862243753, 16014.316392269147, 16825.663486730442, 20345.79654203439, 21070.789292107496, 18450.35423760162, 18486.722699159254, 18678.439646810617, 18758.53103672419, 18863.96248206362, 18268.634627307645, 18818.435446936615, 18910.054497275356, 19147.0, 19328.516787080436, 17702.468925932244, 18205.908970455064, 19082.809171908102, 19130.808691912902, 19291.324803631807, 10980.670579882617, 12248.755024899274, 13093.018023648456, 13317.134386264861, 13718.588442346745, 13198.94408447341, 13941.58175254744, 14097.0, 14184.29078546048, 14846.109233446028], "value": 9176.138202815557, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 111.0, 115.0, 112.0, 111.0, 112.0, 112.0, 117.0, 112.0, 114.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 111.0, 154.0, 152.0, 97.0, 164.0, 166.0, 146.0, 168.0, 108.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 199.0, 196.0, 202.0, 226.0, 222.0, 225.0, 202.0, 204.0, 195.0], "value": 207.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 87.0, 80.0, 135.0, 54.0, 58.0, 69.0, 65.0, 105.0, 86.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 111.0, 69.0, 77.0, 70.0, 113.0, 68.0, 72.0, 112.0], "value": 83.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 89.0, 89.0, 88.0, 90.0, 90.0, 89.0, 90.0, 91.0], "value": 89.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 97.0, 128.0, 98.0, 98.0, 96.0, 98.0, 97.0], "value": 100.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 91.0, 91.0, 91.0, 91.0, 120.0, 148.0, 92.0, 89.0], "value": 99.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 52.0, 54.0, 52.0, 51.0, 57.0, 52.0, 55.0, 52.0, 52.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 58.0, 53.0, 52.0, 60.0, 54.0, 56.0, 54.0, 53.0], "value": 54.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 88.0, 108.0, 103.0, 83.0, 79.0, 80.0, 82.0, 98.0, 104.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 148.0, 144.0, 132.0, 140.0, 137.0, 144.0, 158.0, 173.0], "value": 147.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 222.0, 234.0, 210.0, 175.0, 251.0, 223.0, 206.0, 175.0, 202.0], "value": 207.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 61.0, 80.0, 97.0, 93.0, 93.0, 85.0, 62.0, 69.0, 86.0], "value": 79.2, "unit": "score"}]}]} 12:25:37 INFO - Return code: 0 12:25:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:25:37 INFO - # TBPL SUCCESS # 12:25:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:25:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:25:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:25:37 INFO - 2017-06-21 12:25:37.537 screenresolution[2399:32417] starting screenresolution argv=screenresolution get 12:25:37 INFO - 2017-06-21 12:25:37.539 screenresolution[2399:32417] Display 0: 1600x1200x32@60 12:25:37 INFO - 2017-06-21 12:25:37.546 screenresolution[2400:32420] starting screenresolution argv=screenresolution list 12:25:37 INFO - Available Modes on Display 0 12:25:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:25:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:25:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:25:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:25:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:25:37 INFO - Intel Iris: 12:25:37 INFO - Chipset Model: Intel Iris 12:25:37 INFO - Type: GPU 12:25:37 INFO - Bus: Built-In 12:25:37 INFO - VRAM (Dynamic, Max): 1536 MB 12:25:37 INFO - Vendor: Intel (0x8086) 12:25:37 INFO - Device ID: 0x0a2e 12:25:37 INFO - Revision ID: 0x0009 12:25:37 INFO - Displays: 12:25:37 INFO - Display: 12:25:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:25:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:25:37 INFO - Display Serial Number: 1600x1200 60 12:25:37 INFO - Main Display: Yes 12:25:37 INFO - Mirror: Off 12:25:37 INFO - Online: Yes 12:25:37 INFO - Rotation: Supported 12:25:37 INFO - Return code: 0 12:25:37 INFO - Running post-action listener: _resource_record_post_action 12:25:37 INFO - [mozharness: 2017-06-21 19:25:37.792958Z] Finished run-tests step (success) 12:25:37 INFO - Running post-run listener: _resource_record_post_run 12:25:37 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 0; Write bytes: 568833024; Read time: 0; Write time: 1697 12:25:37 INFO - TinderboxPrint: CPU usage
54.2% 12:25:37 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 12:25:37 INFO - TinderboxPrint: I/O write bytes / time
568,833,024 / 1,697 12:25:37 INFO - TinderboxPrint: CPU idle
2,202.1 (45.8%) 12:25:37 INFO - TinderboxPrint: CPU system
967.7 (20.1%) 12:25:37 INFO - TinderboxPrint: CPU user
1,635.9 (34.0%) 12:25:37 INFO - TinderboxPrint: Swap in / out
253,460,480 / 0 12:25:37 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 249442816; Write bytes: 244660224; Read time: 17862; Write time: 495 12:25:37 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:25:37 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 0; Write bytes: 309922816; Read time: 0; Write time: 1155 12:25:38 INFO - Running post-run listener: _upload_blobber_files 12:25:38 INFO - Blob upload gear active. 12:25:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:25:38 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:25:38 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:25:38 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:25:38 INFO - (blobuploader) - INFO - Open directory for files ... 12:25:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:25:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:25:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:25:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:25:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:25:40 INFO - (blobuploader) - INFO - Done attempting. 12:25:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:25:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:25:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:25:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:25:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:25:41 INFO - (blobuploader) - INFO - Done attempting. 12:25:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:25:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:25:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:25:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:25:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:25:43 INFO - (blobuploader) - INFO - Done attempting. 12:25:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:25:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:25:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:25:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:25:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:25:45 INFO - (blobuploader) - INFO - Done attempting. 12:25:45 INFO - (blobuploader) - INFO - Iteration through files over. 12:25:45 INFO - Return code: 0 12:25:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:25:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:25:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d129299582705431505d2c050867ea2571097366ffe80212dbc75e3ac73b616f59034189ac298c772d50c31c685ae0c3eded330546f518ed6c33309abee5a36e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/4b12939aeb6da7373abe2b25dcfe665e693bffdde3fdf17a1752a8e0283fcb114f1b368ae032fe33314420c43d3e1aa96cf8072ab9c4632f61e9f934d1fd4533", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/865ed961d94e629a49159c2d86bdcaaf74a89233e980b9119174f4910ce00df03368229416baf2419b1b6498fec114cbbdb5f5de07e76406323dc0642e33e8f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7cb0f6c35aa2bff10692814f8efd04a53b7d76d1d1761a77728c141806344078eaeda8cdbb28c58c1b7c6f0d913aa86484f12aefbd16ae709d4100de0a256926"} 12:25:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:25:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:25:45 INFO - Contents: 12:25:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d129299582705431505d2c050867ea2571097366ffe80212dbc75e3ac73b616f59034189ac298c772d50c31c685ae0c3eded330546f518ed6c33309abee5a36e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/4b12939aeb6da7373abe2b25dcfe665e693bffdde3fdf17a1752a8e0283fcb114f1b368ae032fe33314420c43d3e1aa96cf8072ab9c4632f61e9f934d1fd4533", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/865ed961d94e629a49159c2d86bdcaaf74a89233e980b9119174f4910ce00df03368229416baf2419b1b6498fec114cbbdb5f5de07e76406323dc0642e33e8f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7cb0f6c35aa2bff10692814f8efd04a53b7d76d1d1761a77728c141806344078eaeda8cdbb28c58c1b7c6f0d913aa86484f12aefbd16ae709d4100de0a256926"} 12:25:45 INFO - Running post-run listener: copy_logs_to_upload_dir 12:25:45 INFO - Copying logs to upload dir... 12:25:45 INFO - mkdir: /builds/slave/test/build/upload/logs 12:25:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.484359 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-06-21 12:25:45.153690) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-21 12:25:45.157051) ========= 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.gEYUtR9xDo/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.q1jzIBRHRX/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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/d129299582705431505d2c050867ea2571097366ffe80212dbc75e3ac73b616f59034189ac298c772d50c31c685ae0c3eded330546f518ed6c33309abee5a36e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/4b12939aeb6da7373abe2b25dcfe665e693bffdde3fdf17a1752a8e0283fcb114f1b368ae032fe33314420c43d3e1aa96cf8072ab9c4632f61e9f934d1fd4533", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/865ed961d94e629a49159c2d86bdcaaf74a89233e980b9119174f4910ce00df03368229416baf2419b1b6498fec114cbbdb5f5de07e76406323dc0642e33e8f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7cb0f6c35aa2bff10692814f8efd04a53b7d76d1d1761a77728c141806344078eaeda8cdbb28c58c1b7c6f0d913aa86484f12aefbd16ae709d4100de0a256926"} 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.011154 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/d129299582705431505d2c050867ea2571097366ffe80212dbc75e3ac73b616f59034189ac298c772d50c31c685ae0c3eded330546f518ed6c33309abee5a36e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/4b12939aeb6da7373abe2b25dcfe665e693bffdde3fdf17a1752a8e0283fcb114f1b368ae032fe33314420c43d3e1aa96cf8072ab9c4632f61e9f934d1fd4533", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/865ed961d94e629a49159c2d86bdcaaf74a89233e980b9119174f4910ce00df03368229416baf2419b1b6498fec114cbbdb5f5de07e76406323dc0642e33e8f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7cb0f6c35aa2bff10692814f8efd04a53b7d76d1d1761a77728c141806344078eaeda8cdbb28c58c1b7c6f0d913aa86484f12aefbd16ae709d4100de0a256926"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-21 12:25:45.189417) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:25:45.189933) ========= 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.gEYUtR9xDo/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.q1jzIBRHRX/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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.005271 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:25:45.238496) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-21 12:25:45.238820) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-21 12:25:45.239160) ========= ========= Total master_lag: 0.17 =========