builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0025 starttime: 1498072067.93 results: success (0) buildid: 20170621111549 builduid: 2ad414d771ef407f8852b9add51654b4 revision: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:47.927209) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:47.927689) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:47.928008) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:47.952000) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:47.952418) ========= 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.ZO0xM4brs3/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.suUwQngqSS/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-21 12:07:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.9M=0.001s 2017-06-21 12:07:48 (12.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125656 ========= master_lag: 0.29 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:48.366550) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:48.367148) ========= 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.ZO0xM4brs3/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.suUwQngqSS/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.022620 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:48.440739) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-21 12:07:48.441395) ========= 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.ZO0xM4brs3/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.suUwQngqSS/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-21 12:07:48,532 truncating revision to first 12 chars 2017-06-21 12:07:48,532 Setting DEBUG logging. 2017-06-21 12:07:48,532 attempt 1/10 2017-06-21 12:07:48,533 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/4f227bec7585?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-21 12:07:50,152 unpacking tar archive at: pine-4f227bec7585/testing/mozharness/ program finished with exit code 0 elapsedTime=1.870901 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-21 12:07:50.364699) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:50.365099) ========= script_repo_revision: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:50.365909) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:50.366287) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-21 12:07:50.530006) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-06-21 12:07:50.530414) ========= /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.ZO0xM4brs3/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.suUwQngqSS/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:07:50 INFO - MultiFileLogger online at 20170621 12:07:50 in /builds/slave/test 12:07:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 12:07:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:07:50 INFO - {'append_to_log': False, 12:07:50 INFO - 'base_work_dir': '/builds/slave/test', 12:07:50 INFO - 'blob_upload_branch': 'Pine', 12:07:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:07:50 INFO - 'branch': 'Pine', 12:07:50 INFO - 'buildbot_json_path': 'buildprops.json', 12:07:50 INFO - 'config_files': ('talos/mac_config.py',), 12:07:50 INFO - 'default_actions': ('clobber', 12:07:50 INFO - 'read-buildbot-config', 12:07:50 INFO - 'download-and-extract', 12:07:50 INFO - 'populate-webroot', 12:07:50 INFO - 'create-virtualenv', 12:07:50 INFO - 'install', 12:07:50 INFO - 'setup-mitmproxy', 12:07:50 INFO - 'run-tests'), 12:07:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:07:50 INFO - 'download_minidump_stackwalk': True, 12:07:50 INFO - 'download_symbols': 'ondemand', 12:07:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:07:50 INFO - 'tooltool.py': '/tools/tooltool.py', 12:07:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:07:50 INFO - '/tools/misc-python/virtualenv.py')}, 12:07:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:07:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:07:50 INFO - 'gecko_profile': False, 12:07:50 INFO - 'gecko_profile_interval': 0, 12:07:50 INFO - 'installer_path': 'installer.exe', 12:07:50 INFO - 'log_level': 'info', 12:07:50 INFO - 'log_name': 'talos', 12:07:50 INFO - 'log_to_console': True, 12:07:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:07:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:07:50 INFO - 'opt_config_files': (), 12:07:50 INFO - 'pip_index': False, 12:07:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:07:50 INFO - 'cmd': ('bash', 12:07:50 INFO - '-c', 12:07:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:07:50 INFO - 'enabled': True, 12:07:50 INFO - 'halt_on_failure': False, 12:07:50 INFO - 'name': 'check_screen_resolution'},), 12:07:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:07:50 INFO - 'cmd': ('bash', 12:07:50 INFO - '-c', 12:07:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:07:50 INFO - 'enabled': True, 12:07:50 INFO - 'halt_on_failure': False, 12:07:50 INFO - 'name': 'check_screen_resolution'},), 12:07:50 INFO - 'run_cmd_checks_enabled': True, 12:07:50 INFO - 'suite': 'dromaeojs-e10s', 12:07:50 INFO - 'system_bits': '32', 12:07:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:07:50 INFO - 'title': 't-yosemite-r7-0025', 12:07:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:07:50 INFO - 'use_talos_json': True, 12:07:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:07:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:07:50 INFO - 'work_dir': 'build'} 12:07:50 INFO - [mozharness: 2017-06-21 19:07:50.764071Z] Running clobber step. 12:07:50 INFO - Running pre-action listener: _resource_record_pre_action 12:07:50 INFO - Running main action method: clobber 12:07:50 INFO - rmtree: /builds/slave/test/build 12:07:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:07:53 INFO - Running post-action listener: _resource_record_post_action 12:07:53 INFO - [mozharness: 2017-06-21 19:07:53.046075Z] Finished clobber step (success) 12:07:53 INFO - [mozharness: 2017-06-21 19:07:53.046220Z] Running read-buildbot-config step. 12:07:53 INFO - Running pre-action listener: _resource_record_pre_action 12:07:53 INFO - Running main action method: read_buildbot_config 12:07:53 INFO - Using buildbot properties: 12:07:53 INFO - { 12:07:53 INFO - "project": "", 12:07:53 INFO - "product": "firefox", 12:07:53 INFO - "script_repo_revision": "default", 12:07:53 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 12:07:53 INFO - "repository": "", 12:07:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 12:07:53 INFO - "buildid": "20170621111549", 12:07:53 INFO - "stage_platform": "macosx64", 12:07:53 INFO - "basedir": "/builds/slave/test", 12:07:53 INFO - "buildnumber": 0, 12:07:53 INFO - "platform": "yosemite_r7", 12:07:53 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 12:07:53 INFO - "slavebuilddir": "test", 12:07:53 INFO - "branch": "pine", 12:07:53 INFO - "revision": "4f227bec7585a7e4487d7da44c5cc7edd2ad5654", 12:07:53 INFO - "slavename": "t-yosemite-r7-0025", 12:07:53 INFO - "builduid": "2ad414d771ef407f8852b9add51654b4", 12:07:53 INFO - "repo_path": "projects/pine" 12:07:53 INFO - } 12:07:53 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg. 12:07:53 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.test_packages.json. 12:07:53 INFO - Running post-action listener: _resource_record_post_action 12:07:53 INFO - [mozharness: 2017-06-21 19:07:53.049612Z] Finished read-buildbot-config step (success) 12:07:53 INFO - [mozharness: 2017-06-21 19:07:53.049745Z] Running download-and-extract step. 12:07:53 INFO - Running pre-action listener: _resource_record_pre_action 12:07:53 INFO - Running main action method: download_and_extract 12:07:53 INFO - mkdir: /builds/slave/test/build/tests 12:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:53 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.test_packages.json 12:07:53 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 12:07:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json'}, attempt #1 12:07:53 INFO - Downloaded 1328 bytes. 12:07:53 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 12:07:53 INFO - Using the following test package requirements: 12:07:53 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:53 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 12:07:53 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 12:07:53 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:53 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 12:07:53 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:53 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 12:07:53 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:53 INFO - u'jsshell-mac.zip'], 12:07:53 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:53 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 12:07:53 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 12:07:53 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:53 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 12:07:53 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:53 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 12:07:53 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:53 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 12:07:53 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:07:53 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 12:07:53 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:07:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip 12:07:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:07:53 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:07:54 INFO - Content-Length response header: 59596040 12:07:54 INFO - Bytes received: 59596040 12:08:12 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip', u'firefox-56.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:08:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip 12:08:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:08:12 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.common.tests.zip into memory 12:08:14 INFO - Content-Length response header: 59596040 12:08:14 INFO - Bytes received: 59596040 12:08:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.talos.tests.zip 12:08:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:08:31 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 12:08:32 INFO - Content-Length response header: 14457085 12:08:32 INFO - Bytes received: 14457085 12:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:32 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg 12:08:32 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 12:08:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg'}, attempt #1 12:08:34 INFO - Downloaded 69703961 bytes. 12:08:34 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg 12:08:34 INFO - mkdir: /builds/slave/test/properties 12:08:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:08:34 INFO - Writing to file /builds/slave/test/properties/build_url 12:08:34 INFO - Contents: 12:08:34 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1497990059/firefox-56.0a1.en-US.mac.dmg 12:08:34 INFO - Running post-action listener: _resource_record_post_action 12:08:34 INFO - Running post-action listener: set_extra_try_arguments 12:08:34 INFO - [mozharness: 2017-06-21 19:08:34.590697Z] Finished download-and-extract step (success) 12:08:34 INFO - [mozharness: 2017-06-21 19:08:34.590844Z] Running populate-webroot step. 12:08:34 INFO - Running pre-action listener: _resource_record_pre_action 12:08:34 INFO - Running main action method: populate_webroot 12:08:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:08:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:08:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:08:34 INFO - '--tppagecycles', 12:08:34 INFO - '7'], 12:08:34 INFO - 'tests': ['tsvgm']}}, 12:08:34 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:08:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:08:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:08:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:08:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:08:34 INFO - 'talos_options': ['--disable-e10s'], 12:08:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:08:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:08:34 INFO - 'talos_options': ['--disable-e10s'], 12:08:34 INFO - 'tests': ['damp', 'tps']}, 12:08:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:08:34 INFO - 'tests': ['damp', 'tps']}, 12:08:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:08:34 INFO - 'tests': ['dromaeo_dom']}, 12:08:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:08:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:08:34 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:08:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:08:34 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:08:34 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:08:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:08:34 INFO - 'tests': ['a11yr', 12:08:34 INFO - 'ts_paint', 12:08:34 INFO - 'tpaint', 12:08:34 INFO - 'sessionrestore', 12:08:34 INFO - 'sessionrestore_no_auto_restore', 12:08:34 INFO - 'tabpaint']}, 12:08:34 INFO - 'other-e10s': {'tests': ['a11yr', 12:08:34 INFO - 'ts_paint', 12:08:34 INFO - 'tpaint', 12:08:34 INFO - 'sessionrestore', 12:08:34 INFO - 'sessionrestore_no_auto_restore', 12:08:34 INFO - 'tabpaint']}, 12:08:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:08:34 INFO - 'ts_paint', 12:08:34 INFO - 'tpaint', 12:08:34 INFO - 'sessionrestore', 12:08:34 INFO - 'sessionrestore_no_auto_restore', 12:08:34 INFO - 'tabpaint']}, 12:08:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:08:34 INFO - 'ts_paint', 12:08:34 INFO - 'tpaint', 12:08:34 INFO - 'sessionrestore', 12:08:34 INFO - 'sessionrestore_no_auto_restore', 12:08:34 INFO - 'tabpaint']}, 12:08:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:08:34 INFO - 'tests': ['a11yr', 12:08:34 INFO - 'ts_paint', 12:08:34 INFO - 'tpaint', 12:08:34 INFO - 'sessionrestore', 12:08:34 INFO - 'sessionrestore_no_auto_restore', 12:08:34 INFO - 'tabpaint']}, 12:08:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:08:34 INFO - 'tests': ['a11yr', 12:08:34 INFO - 'ts_paint', 12:08:34 INFO - 'tpaint', 12:08:34 INFO - 'sessionrestore', 12:08:34 INFO - 'sessionrestore_no_auto_restore', 12:08:34 INFO - 'tabpaint']}, 12:08:34 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:08:34 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:08:34 INFO - 'bloom_basic_ref']}, 12:08:34 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:08:34 INFO - 'talos_options': ['--mitmproxy', 12:08:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:08:34 INFO - '--firstNonBlankPaint'], 12:08:34 INFO - 'tests': ['quantum_pageload_google', 12:08:34 INFO - 'quantum_pageload_youtube', 12:08:34 INFO - 'quantum_pageload_amazon', 12:08:34 INFO - 'quantum_pageload_facebook']}, 12:08:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:08:34 INFO - 'tests': ['tsvgx', 12:08:34 INFO - 'tsvgr_opacity', 12:08:34 INFO - 'tart', 12:08:34 INFO - 'tscrollx', 12:08:34 INFO - 'cart', 12:08:34 INFO - 'tsvg_static']}, 12:08:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:08:34 INFO - 'tsvgr_opacity', 12:08:34 INFO - 'tart', 12:08:34 INFO - 'tscrollx', 12:08:34 INFO - 'cart', 12:08:34 INFO - 'tsvg_static']}, 12:08:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:08:34 INFO - 'talos_options': ['--disable-e10s'], 12:08:34 INFO - 'tests': ['tp5o']}, 12:08:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:08:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:08:34 INFO - 'talos_options': ['--disable-e10s', 12:08:34 INFO - '--xperf_path', 12:08:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:34 INFO - 'tests': ['tp5n']}, 12:08:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:08:34 INFO - 'talos_options': ['--xperf_path', 12:08:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:34 INFO - 'tests': ['tp5n']}}, 12:08:34 INFO - 'talos.zip': {'path': '', 12:08:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:08:34 INFO - Running post-action listener: _resource_record_post_action 12:08:34 INFO - [mozharness: 2017-06-21 19:08:34.611166Z] Finished populate-webroot step (success) 12:08:34 INFO - [mozharness: 2017-06-21 19:08:34.611256Z] Running create-virtualenv step. 12:08:34 INFO - Running pre-action listener: _resource_record_pre_action 12:08:34 INFO - Running main action method: create_virtualenv 12:08:34 INFO - Creating virtualenv /builds/slave/test/build/venv 12:08:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:08:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:08:34 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:08:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:08:34 INFO - Using real prefix '/tools/python27' 12:08:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:08:35 INFO - Installing distribute.............................................................................................................................................................................................done. 12:08:38 INFO - Installing pip.................done. 12:08:38 INFO - Return code: 0 12:08:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:08:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:08:38 INFO - Reading from file tmpfile_stdout 12:08:38 INFO - Output received: 12:08:38 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:08:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:38 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 0x10c272c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb21579650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfc8d30>, '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 0x10bf92cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb9be8>, '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.ZO0xM4brs3/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.suUwQngqSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:08:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:08:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZO0xM4brs3/Render', 12:08:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:38 INFO - 'HOME': '/Users/cltbld', 12:08:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:38 INFO - 'LOGNAME': 'cltbld', 12:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:38 INFO - 'MOZ_NO_REMOTE': '1', 12:08:38 INFO - 'NO_EM_RESTART': '1', 12:08:38 INFO - 'PAGER': '/bin/cat', 12:08:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:38 INFO - 'PWD': '/builds/slave/test', 12:08:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:38 INFO - 'SHELL': '/bin/bash', 12:08:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.suUwQngqSS/Listeners', 12:08:38 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 12:08:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:38 INFO - 'USER': 'cltbld', 12:08:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:38 INFO - 'XPC_FLAGS': '0x0', 12:08:38 INFO - 'XPC_SERVICE_NAME': '0', 12:08:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:08:38 INFO - Cleaning up... 12:08:38 INFO - Return code: 0 12:08:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:38 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 0x10c272c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb21579650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfc8d30>, '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 0x10bf92cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb9be8>, '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.ZO0xM4brs3/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.suUwQngqSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:08:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:08:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZO0xM4brs3/Render', 12:08:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:38 INFO - 'HOME': '/Users/cltbld', 12:08:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:38 INFO - 'LOGNAME': 'cltbld', 12:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:38 INFO - 'MOZ_NO_REMOTE': '1', 12:08:38 INFO - 'NO_EM_RESTART': '1', 12:08:38 INFO - 'PAGER': '/bin/cat', 12:08:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:38 INFO - 'PWD': '/builds/slave/test', 12:08:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:38 INFO - 'SHELL': '/bin/bash', 12:08:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.suUwQngqSS/Listeners', 12:08:38 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 12:08:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:38 INFO - 'USER': 'cltbld', 12:08:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:38 INFO - 'XPC_FLAGS': '0x0', 12:08:38 INFO - 'XPC_SERVICE_NAME': '0', 12:08:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:39 INFO - Downloading/unpacking psutil>=3.1.1 12:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:08:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:08:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:08:41 INFO - Installing collected packages: psutil 12:08:41 INFO - Running setup.py install for psutil 12:08:41 INFO - building 'psutil._psutil_osx' extension 12:08:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:08:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:08:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:08:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:08:41 INFO - building 'psutil._psutil_posix' extension 12:08:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:08:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:08:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:08:41 INFO - ^ 12:08:41 INFO - 1 warning generated. 12:08:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:08:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:08:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:08:41 INFO - Successfully installed psutil 12:08:41 INFO - Cleaning up... 12:08:42 INFO - Return code: 0 12:08:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c272c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb21579650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfc8d30>, '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 0x10bf92cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb9be8>, '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.ZO0xM4brs3/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.suUwQngqSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:08:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:08:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZO0xM4brs3/Render', 12:08:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:42 INFO - 'HOME': '/Users/cltbld', 12:08:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:42 INFO - 'LOGNAME': 'cltbld', 12:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:42 INFO - 'MOZ_NO_REMOTE': '1', 12:08:42 INFO - 'NO_EM_RESTART': '1', 12:08:42 INFO - 'PAGER': '/bin/cat', 12:08:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:42 INFO - 'PWD': '/builds/slave/test', 12:08:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:42 INFO - 'SHELL': '/bin/bash', 12:08:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.suUwQngqSS/Listeners', 12:08:42 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 12:08:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:42 INFO - 'USER': 'cltbld', 12:08:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:42 INFO - 'XPC_FLAGS': '0x0', 12:08:42 INFO - 'XPC_SERVICE_NAME': '0', 12:08:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:08:43 INFO - Installing collected packages: mozsystemmonitor 12:08:43 INFO - Running setup.py install for mozsystemmonitor 12:08:43 INFO - Successfully installed mozsystemmonitor 12:08:43 INFO - Cleaning up... 12:08:43 INFO - Return code: 0 12:08:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:08:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c272c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb21579650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfc8d30>, '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 0x10bf92cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb9be8>, '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.ZO0xM4brs3/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.suUwQngqSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:08:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:08:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZO0xM4brs3/Render', 12:08:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:43 INFO - 'HOME': '/Users/cltbld', 12:08:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:43 INFO - 'LOGNAME': 'cltbld', 12:08:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:43 INFO - 'MOZ_NO_REMOTE': '1', 12:08:43 INFO - 'NO_EM_RESTART': '1', 12:08:43 INFO - 'PAGER': '/bin/cat', 12:08:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:43 INFO - 'PWD': '/builds/slave/test', 12:08:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:43 INFO - 'SHELL': '/bin/bash', 12:08:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.suUwQngqSS/Listeners', 12:08:43 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 12:08:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:43 INFO - 'USER': 'cltbld', 12:08:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:43 INFO - 'XPC_FLAGS': '0x0', 12:08:43 INFO - 'XPC_SERVICE_NAME': '0', 12:08:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:44 INFO - Downloading/unpacking jsonschema==2.5.1 12:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:08:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:46 INFO - Downloading functools32-3.2.3-2.tar.gz 12:08:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:08:46 INFO - warning: no files found matching '*.txt' 12:08:46 INFO - no previously-included directories found matching 'build' 12:08:46 INFO - no previously-included directories found matching 'dist' 12:08:46 INFO - no previously-included directories found matching '.git*' 12:08:46 INFO - Installing collected packages: jsonschema, functools32 12:08:46 INFO - Running setup.py install for functools32 12:08:46 INFO - warning: no files found matching '*.txt' 12:08:46 INFO - no previously-included directories found matching 'build' 12:08:46 INFO - no previously-included directories found matching 'dist' 12:08:46 INFO - no previously-included directories found matching '.git*' 12:08:46 INFO - Successfully installed jsonschema functools32 12:08:46 INFO - Cleaning up... 12:08:46 INFO - Return code: 0 12:08:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c272c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb21579650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfc8d30>, '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 0x10bf92cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb9be8>, '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.ZO0xM4brs3/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.suUwQngqSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:08:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:08:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZO0xM4brs3/Render', 12:08:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:47 INFO - 'HOME': '/Users/cltbld', 12:08:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:47 INFO - 'LOGNAME': 'cltbld', 12:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:47 INFO - 'MOZ_NO_REMOTE': '1', 12:08:47 INFO - 'NO_EM_RESTART': '1', 12:08:47 INFO - 'PAGER': '/bin/cat', 12:08:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:47 INFO - 'PWD': '/builds/slave/test', 12:08:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:47 INFO - 'SHELL': '/bin/bash', 12:08:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.suUwQngqSS/Listeners', 12:08:47 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 12:08:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:47 INFO - 'USER': 'cltbld', 12:08:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:47 INFO - 'XPC_FLAGS': '0x0', 12:08:47 INFO - 'XPC_SERVICE_NAME': '0', 12:08:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:08:47 INFO - Cleaning up... 12:08:47 INFO - Return code: 0 12:08:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c272c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb21579650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfc8d30>, '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 0x10bf92cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb9be8>, '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.ZO0xM4brs3/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.suUwQngqSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:08:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZO0xM4brs3/Render', 12:08:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:48 INFO - 'HOME': '/Users/cltbld', 12:08:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:48 INFO - 'LOGNAME': 'cltbld', 12:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:48 INFO - 'MOZ_NO_REMOTE': '1', 12:08:48 INFO - 'NO_EM_RESTART': '1', 12:08:48 INFO - 'PAGER': '/bin/cat', 12:08:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:48 INFO - 'PWD': '/builds/slave/test', 12:08:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:48 INFO - 'SHELL': '/bin/bash', 12:08:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.suUwQngqSS/Listeners', 12:08:48 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 12:08:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:48 INFO - 'USER': 'cltbld', 12:08:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:48 INFO - 'XPC_FLAGS': '0x0', 12:08:48 INFO - 'XPC_SERVICE_NAME': '0', 12:08:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:48 INFO - Downloading/unpacking blobuploader==1.2.4 12:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:49 INFO - Downloading blobuploader-1.2.4.tar.gz 12:08:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:08:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:08:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:50 INFO - Downloading docopt-0.6.1.tar.gz 12:08:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:08:50 INFO - Installing collected packages: blobuploader, requests, docopt 12:08:50 INFO - Running setup.py install for blobuploader 12:08:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:08:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:08:50 INFO - Running setup.py install for requests 12:08:51 INFO - Running setup.py install for docopt 12:08:51 INFO - Successfully installed blobuploader requests docopt 12:08:51 INFO - Cleaning up... 12:08:51 INFO - Return code: 0 12:08:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:51 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 0x10c272c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb21579650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfc8d30>, '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 0x10bf92cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb9be8>, '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.ZO0xM4brs3/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.suUwQngqSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:08:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZO0xM4brs3/Render', 12:08:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:51 INFO - 'HOME': '/Users/cltbld', 12:08:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:51 INFO - 'LOGNAME': 'cltbld', 12:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:51 INFO - 'MOZ_NO_REMOTE': '1', 12:08:51 INFO - 'NO_EM_RESTART': '1', 12:08:51 INFO - 'PAGER': '/bin/cat', 12:08:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:51 INFO - 'PWD': '/builds/slave/test', 12:08:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:51 INFO - 'SHELL': '/bin/bash', 12:08:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.suUwQngqSS/Listeners', 12:08:51 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 12:08:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:51 INFO - 'USER': 'cltbld', 12:08:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:51 INFO - 'XPC_FLAGS': '0x0', 12:08:51 INFO - 'XPC_SERVICE_NAME': '0', 12:08:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:51 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-H3nmGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-IsFMYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-C5JWrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-x_aSFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:08:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-DxTl5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:08:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-u4pzII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:08:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-b_YXMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:08:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-l0uayt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:08:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-qlmFkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:08:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-EDmHem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:08:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-RrZEuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:08:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-iJCcMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:08:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-U502zg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:08:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-NmXr7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-O4AOCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:08:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-0kcN1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:08:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-xWda3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:08:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:08:53 INFO - Running setup.py install for manifestparser 12:08:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:08:53 INFO - Running setup.py install for mozcrash 12:08:53 INFO - Running setup.py install for mozdebug 12:08:54 INFO - Running setup.py install for mozdevice 12:08:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:08:54 INFO - Running setup.py install for mozfile 12:08:54 INFO - Running setup.py install for mozhttpd 12:08:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:08:54 INFO - Running setup.py install for mozinfo 12:08:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:08:54 INFO - Running setup.py install for mozInstall 12:08:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:08:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:08:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:08:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:08:54 INFO - Running setup.py install for mozleak 12:08:54 INFO - Running setup.py install for mozlog 12:08:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:08:55 INFO - Running setup.py install for moznetwork 12:08:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:08:55 INFO - Running setup.py install for mozprocess 12:08:55 INFO - Running setup.py install for mozprofile 12:08:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:08:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:08:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:08:55 INFO - Running setup.py install for mozrunner 12:08:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:08:55 INFO - Running setup.py install for mozscreenshot 12:08:55 INFO - Running setup.py install for moztest 12:08:56 INFO - Running setup.py install for mozversion 12:08:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:08:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:08:56 INFO - Cleaning up... 12:08:56 INFO - Return code: 0 12:08:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:56 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 0x10c272c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb21579650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfc8d30>, '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 0x10bf92cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb9be8>, '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.ZO0xM4brs3/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.suUwQngqSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:56 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:08:56 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:08:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZO0xM4brs3/Render', 12:08:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:56 INFO - 'HOME': '/Users/cltbld', 12:08:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:56 INFO - 'LOGNAME': 'cltbld', 12:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:56 INFO - 'MOZ_NO_REMOTE': '1', 12:08:56 INFO - 'NO_EM_RESTART': '1', 12:08:56 INFO - 'PAGER': '/bin/cat', 12:08:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:56 INFO - 'PWD': '/builds/slave/test', 12:08:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:56 INFO - 'SHELL': '/bin/bash', 12:08:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.suUwQngqSS/Listeners', 12:08:56 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 12:08:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:56 INFO - 'USER': 'cltbld', 12:08:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:56 INFO - 'XPC_FLAGS': '0x0', 12:08:56 INFO - 'XPC_SERVICE_NAME': '0', 12:08:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:56 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-aN0UUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08:56 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:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:56 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-3yjOxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:56 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:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:56 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-MOTaMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:56 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:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:56 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-OrJihK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:08:57 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:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:08:57 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-8n1vwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:08:57 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:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:08:57 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-1KlyXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:08:57 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:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:08:57 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-DSTpKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:08:57 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:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:08:57 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-tjnzfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:08:57 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:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:08:57 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-wqq8rt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:08:57 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:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:08:57 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-eLAu_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:08:57 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:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:08:57 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-wSgjER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:08:57 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:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:08:57 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-g5K6jQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:08:57 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:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:08:57 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-6onu6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:08:57 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:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:08:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-A92zq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:08:58 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:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-K3Ef6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:58 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:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:08:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-tmh1dQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:08:58 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:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:08:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-oxRV7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:08:58 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:08:58 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:08:58 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:08:58 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:08:58 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:08:58 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:08:58 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:08:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:59 INFO - Downloading blessings-1.6.tar.gz 12:08:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:08:59 INFO - Installing collected packages: blessings 12:08:59 INFO - Running setup.py install for blessings 12:09:00 INFO - Successfully installed blessings 12:09:00 INFO - Cleaning up... 12:09:00 INFO - Return code: 0 12:09:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:09:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:00 INFO - Reading from file tmpfile_stdout 12:09:00 INFO - Current package versions: 12:09:00 INFO - blessings == 1.6 12:09:00 INFO - blobuploader == 1.2.4 12:09:00 INFO - docopt == 0.6.1 12:09:00 INFO - functools32 == 3.2.3-2 12:09:00 INFO - jsonschema == 2.5.1 12:09:00 INFO - manifestparser == 1.1 12:09:00 INFO - mozInstall == 1.13 12:09:00 INFO - mozcrash == 1.0 12:09:00 INFO - mozdebug == 0.1 12:09:00 INFO - mozdevice == 0.51 12:09:00 INFO - mozfile == 1.2 12:09:00 INFO - mozhttpd == 0.7 12:09:00 INFO - mozinfo == 0.10 12:09:00 INFO - mozleak == 0.1 12:09:00 INFO - mozlog == 3.4 12:09:00 INFO - moznetwork == 0.27 12:09:00 INFO - mozprocess == 0.25 12:09:00 INFO - mozprofile == 0.28 12:09:00 INFO - mozrunner == 6.13 12:09:00 INFO - mozscreenshot == 0.1 12:09:00 INFO - mozsystemmonitor == 0.3 12:09:00 INFO - moztest == 0.8 12:09:00 INFO - mozversion == 1.4 12:09:00 INFO - psutil == 3.1.1 12:09:00 INFO - requests == 1.2.3 12:09:00 INFO - wsgiref == 0.1.2 12:09:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:00 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 0x10c272c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb21579650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfc8d30>, '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 0x10bf92cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb9be8>, '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.ZO0xM4brs3/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.suUwQngqSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:09:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:09:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZO0xM4brs3/Render', 12:09:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:00 INFO - 'HOME': '/Users/cltbld', 12:09:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:00 INFO - 'LOGNAME': 'cltbld', 12:09:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:00 INFO - 'MOZ_NO_REMOTE': '1', 12:09:00 INFO - 'NO_EM_RESTART': '1', 12:09:00 INFO - 'PAGER': '/bin/cat', 12:09:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:00 INFO - 'PWD': '/builds/slave/test', 12:09:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:00 INFO - 'SHELL': '/bin/bash', 12:09:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.suUwQngqSS/Listeners', 12:09:00 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 12:09:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:00 INFO - 'USER': 'cltbld', 12:09:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:00 INFO - 'XPC_FLAGS': '0x0', 12:09:00 INFO - 'XPC_SERVICE_NAME': '0', 12:09:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:09:00 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:02 INFO - Downloading marionette_driver-1.4.0.tar.gz 12:09:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 12:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:09:02 INFO - Installing collected packages: marionette-driver 12:09:02 INFO - Running setup.py install for marionette-driver 12:09:02 INFO - Successfully installed marionette-driver 12:09:02 INFO - Cleaning up... 12:09:02 INFO - Return code: 0 12:09:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--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 0x10c272c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c276768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb21579650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfc8d30>, '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 0x10bf92cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb9be8>, '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.ZO0xM4brs3/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.suUwQngqSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:09:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZO0xM4brs3/Render', 12:09:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:02 INFO - 'HOME': '/Users/cltbld', 12:09:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:02 INFO - 'LOGNAME': 'cltbld', 12:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:02 INFO - 'MOZ_NO_REMOTE': '1', 12:09:02 INFO - 'NO_EM_RESTART': '1', 12:09:02 INFO - 'PAGER': '/bin/cat', 12:09:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:02 INFO - 'PWD': '/builds/slave/test', 12:09:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:02 INFO - 'SHELL': '/bin/bash', 12:09:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.suUwQngqSS/Listeners', 12:09:02 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 12:09:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:02 INFO - 'USER': 'cltbld', 12:09:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:02 INFO - 'XPC_FLAGS': '0x0', 12:09:02 INFO - 'XPC_SERVICE_NAME': '0', 12:09:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:09:02 INFO - Cleaning up... 12:09:02 INFO - Return code: 0 12:09:02 INFO - Running post-action listener: _resource_record_post_action 12:09:02 INFO - Running post-action listener: _start_resource_monitoring 12:09:02 INFO - Starting resource monitoring. 12:09:02 INFO - [mozharness: 2017-06-21 19:09:02.741000Z] Finished create-virtualenv step (success) 12:09:02 INFO - [mozharness: 2017-06-21 19:09:02.742002Z] Running install step. 12:09:02 INFO - Running pre-action listener: _resource_record_pre_action 12:09:02 INFO - Running main action method: install 12:09:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:03 INFO - Reading from file tmpfile_stdout 12:09:03 INFO - Detecting whether we're running mozinstall >=1.0... 12:09:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:09:03 INFO - Reading from file tmpfile_stdout 12:09:03 INFO - Output received: 12:09:03 INFO - Usage: mozinstall [options] installer 12:09:03 INFO - Options: 12:09:03 INFO - -h, --help show this help message and exit 12:09:03 INFO - -d DEST, --destination=DEST 12:09:03 INFO - Directory to install application into. [default: 12:09:03 INFO - "/builds/slave/test"] 12:09:03 INFO - --app=APP Application being installed. [default: firefox] 12:09:03 INFO - mkdir: /builds/slave/test/build/application 12:09:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:09:25 INFO - Reading from file tmpfile_stdout 12:09:25 INFO - Output received: 12:09:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:09:25 INFO - Running post-action listener: _resource_record_post_action 12:09:25 INFO - [mozharness: 2017-06-21 19:09:25.400978Z] Finished install step (success) 12:09:25 INFO - [mozharness: 2017-06-21 19:09:25.401105Z] Running setup-mitmproxy step. 12:09:25 INFO - Running pre-action listener: _resource_record_pre_action 12:09:25 INFO - Running main action method: setup_mitmproxy 12:09:25 INFO - Skipping: mitmproxy is not required 12:09:25 INFO - Running post-action listener: _resource_record_post_action 12:09:25 INFO - [mozharness: 2017-06-21 19:09:25.401725Z] Finished setup-mitmproxy step (success) 12:09:25 INFO - [mozharness: 2017-06-21 19:09:25.401867Z] Running run-tests step. 12:09:25 INFO - Running pre-action listener: _resource_record_pre_action 12:09:25 INFO - Running main action method: run_tests 12:09:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:09:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:09:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:09:25 INFO - 2017-06-21 12:09:25.416 screenresolution[2459:29645] starting screenresolution argv=screenresolution get 12:09:25 INFO - 2017-06-21 12:09:25.419 screenresolution[2459:29645] Display 0: 1600x1200x32@60 12:09:25 INFO - 2017-06-21 12:09:25.425 screenresolution[2460:29648] starting screenresolution argv=screenresolution list 12:09:25 INFO - Available Modes on Display 0 12:09:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:09:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:09:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:09:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:09:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:09:25 INFO - Intel Iris: 12:09:25 INFO - Chipset Model: Intel Iris 12:09:25 INFO - Type: GPU 12:09:25 INFO - Bus: Built-In 12:09:25 INFO - VRAM (Dynamic, Max): 1536 MB 12:09:25 INFO - Vendor: Intel (0x8086) 12:09:25 INFO - Device ID: 0x0a2e 12:09:25 INFO - Revision ID: 0x0009 12:09:25 INFO - Displays: 12:09:25 INFO - Display: 12:09:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:09:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:09:25 INFO - Display Serial Number: 1600x1200 60 12:09:25 INFO - Main Display: Yes 12:09:25 INFO - Mirror: Off 12:09:25 INFO - Online: Yes 12:09:25 INFO - Rotation: Supported 12:09:25 INFO - Return code: 0 12:09:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:09:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:09:25 INFO - Python 2.7.3 12:09:25 INFO - Return code: 0 12:09:25 INFO - grabbing minidump binary from tooltool 12:09:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:25 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 0x10bfc8d30>, '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 0x10bf92cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfb9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:09:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:09:25 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:09:25 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:09:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:09:25 INFO - Return code: 0 12:09:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:09:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:09:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:09:25 INFO - ENV: RUST_BACKTRACE is now 1 12:09:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:09:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:09:25 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-0025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:09:25 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-0025 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:09:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZO0xM4brs3/Render', 12:09:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:25 INFO - 'HOME': '/Users/cltbld', 12:09:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:25 INFO - 'LOGNAME': 'cltbld', 12:09:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:09:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:09:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:25 INFO - 'MOZ_NO_REMOTE': '1', 12:09:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:09:25 INFO - 'NO_EM_RESTART': '1', 12:09:25 INFO - 'PAGER': '/bin/cat', 12:09:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:25 INFO - 'PWD': '/builds/slave/test', 12:09:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:09:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:25 INFO - 'RUST_BACKTRACE': '1', 12:09:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:09:25 INFO - 'SHELL': '/bin/bash', 12:09:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.suUwQngqSS/Listeners', 12:09:25 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 12:09:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:25 INFO - 'USER': 'cltbld', 12:09:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:25 INFO - 'XPC_FLAGS': '0x0', 12:09:25 INFO - 'XPC_SERVICE_NAME': '0', 12:09:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:25 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-0025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:09:26 INFO - mozversion application_buildid: 20170620132059 12:09:26 INFO - mozversion application_changeset: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 12:09:26 INFO - mozversion application_display_name: Nightly 12:09:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:09:26 INFO - mozversion application_name: Firefox 12:09:26 INFO - mozversion application_remotingname: firefox 12:09:26 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 12:09:26 INFO - mozversion application_vendor: Mozilla 12:09:26 INFO - mozversion application_version: 56.0a1 12:09:26 INFO - mozversion platform_buildid: 20170620132059 12:09:26 INFO - mozversion platform_changeset: 4f227bec7585a7e4487d7da44c5cc7edd2ad5654 12:09:26 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 12:09:26 INFO - mozversion platform_version: 56.0a1 12:09:26 INFO - using testdate: 1498072166 12:09:26 INFO - actual date: 1498072166 12:09:26 INFO - starting webserver on 'localhost:57176' 12:09:26 INFO - SUITE-START | Running 2 tests 12:09:26 INFO - TEST-START | dromaeo_css 12:09:26 INFO - operating with platform_type : mac_ 12:09:26 INFO - Initialising browser for dromaeo_css test... 12:09:26 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:57176/getInfo.html -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpOClURe/profile 12:09:26 INFO - TEST-INFO | started process 2470 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:57176/getInfo.html -foreground) 12:09:30 INFO - TEST-INFO | 2470: exit 0 12:09:30 INFO - Browser initialized. 12:09:30 INFO - Running cycle 1/1 for dromaeo_css test... 12:09:30 INFO - TEST-INFO | started process 2476 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpOClURe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:09:32 INFO - PID 2476 | 2017-06-21 12:09:32.355 plugin-container[2477:30029] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 12:09:32 INFO - PID 2476 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:09:32 INFO - PID 2476 | 2017-06-21 12:09:32.357 plugin-container[2477:30029] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 12:09:32 INFO - PID 2476 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:09:32 INFO - PID 2476 | 2017-06-21 12:09:32.358 plugin-container[2477:30029] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:09:33 INFO - PID 2476 | 2017-06-21 12:09:33.140 plugin-container[2478:30081] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x984f, name = 'com.apple.tsm.portname' 12:09:33 INFO - PID 2476 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:09:33 INFO - PID 2476 | 2017-06-21 12:09:33.140 plugin-container[2478:30081] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4813, name = 'com.apple.CFPasteboardClient' 12:09:33 INFO - PID 2476 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:09:33 INFO - PID 2476 | 2017-06-21 12:09:33.140 plugin-container[2478:30081] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:09:42 INFO - PID 2476 | RSS: Main: 249573376 12:09:42 INFO - PID 2476 | 12:12:48 INFO - PID 2476 | Cycle 1(1): loaded http://localhost:57176/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57176/tests/dromaeo/cssquery-ext.html) 12:12:50 INFO - PID 2476 | RSS: Main: 266928128 12:12:50 INFO - PID 2476 | 12:15:52 INFO - PID 2476 | Cycle 1(1): loaded http://localhost:57176/tests/dromaeo/cssquery-ext.html (next: http://localhost:57176/tests/dromaeo/cssquery-jquery.html) 12:15:53 INFO - PID 2476 | RSS: Main: 269451264 12:15:53 INFO - PID 2476 | 12:18:54 INFO - PID 2476 | Cycle 1(1): loaded http://localhost:57176/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57176/tests/dromaeo/cssquery-mootools.html) 12:18:55 INFO - PID 2476 | RSS: Main: 272211968 12:18:55 INFO - PID 2476 | 12:21:56 INFO - PID 2476 | Cycle 1(1): loaded http://localhost:57176/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57176/tests/dromaeo/cssquery-prototype.html) 12:21:57 INFO - PID 2476 | RSS: Main: 270090240 12:21:57 INFO - PID 2476 | 12:25:00 INFO - PID 2476 | Cycle 1(1): loaded http://localhost:57176/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57176/tests/dromaeo/cssquery-yui.html) 12:25:01 INFO - PID 2476 | RSS: Main: 272289792 12:25:01 INFO - PID 2476 | 12:28:02 INFO - PID 2476 | Cycle 1(1): loaded http://localhost:57176/tests/dromaeo/cssquery-yui.html (next: http://localhost:57176/tests/dromaeo/cssquery-dojo.html) 12:28:02 INFO - PID 2476 | RSS: Main: 282783744 12:28:02 INFO - PID 2476 | 12:28:02 INFO - PID 2476 | __start_tp_report 12:28:02 INFO - PID 2476 | _x_x_mozilla_page_load 12:28:02 INFO - PID 2476 | _x_x_mozilla_page_load_details 12:28:02 INFO - PID 2476 | |i|pagename|runs| 12:28:02 INFO - PID 2476 | |0;dojo.html;3578.141246100936;3592.5150104735885;3617.945730814037;3641.7632853864498;3642.4354225095103;6364.109024736541;6402.967985160073;6431.196100487439;6454.935450645504;6471.449926756231;6447.710457908413;6462.903056454145;6474.15835941327;6483.124778154959;6485.870282594346;5606.131049687305;5614.017546929292;5647.604667673265;5766.077427611583;5779.7688092476465;5483.479069488392;5665.773369065253;5673.773049078052;5678.318601767794;5703.116017017348;5530.032244357221;5566.137248726413;5580.581456390787;5582.637128586629;5600.411956744565;5526.585506087019;5582.134769110794;5589.664620122765;5601.6358936668985;5620.915686264669;4839.975800120977;5485.286912701324;5630.8029218977135;5635.098384258499;5691.743871525791;5285.7357132143625;5926.97036514819;6432.839179020562;6453.83865403364;6478.2873883872735;5824.650520968713;6929.203141638675;7023.438124949992;7036.92963070373;7067.43460523157;6835.58986460809;6848.760293389708;6870.519063665545;6906.620135892524;6969.686364113627;6351.237851457855;6424.38968298011;6425.903611445832;6497.64262965532;6517.3808488193545;7018.9298107019295;7084.787456376317;7116.786496405116;7126.786196414012;7138.964305178545;6633.800985970429;6638.535302528873;6749.392554670005;6783.491238157059;6786.864262714718;6562.114114594564;6945.687444065029;6960.095187625576;6964.756233531802;6968.372846443743;6611.834704132435;6650.301718319507;6701.1623547056615;6702.966485167543;6712.563683360566;5950.375210602824;6691.1636045494315;6696.0960270362875;6818.931810681929;6867.450603951672;4490.640748740093;5172.629253247892;5241.868953276198;5365.141577347684;5375.301210842644;4555.794989225493;4713.764311784397;4716.174669432836;4789.209780386267;4854.563089321978;6082.300535438481;6127.20346354971;6235.407636274546;6259.906101408483;6260.874782504325;6669.733210671616;6781.38967492928;6804.65976701163;6813.080234168423;6902.309769023058;6114.11489084418;6181.690915454209;6215.12988181655;6227.314995350508;6239.688015599202;5790.826275211751;5891.941080589139;5897.051474262868;5916.9941110011405;5936.673482958392;6838.760643377458;6854.383105520527;6874.65626718652;6931.757388491378;7066.858662826818;6520.152380190545;6760.425363844129;6764.86470270582;6789.049533065474;6806.149231346082;6874.072000279898;6884.724611015503;7037.1555413350725;7086.645667716491;7100.254473280438;6504.479641628753;6684.83287917806;6780.32196780318;6821.897671534832;6841.3842754152365;6772.55978361405;6829.941359089349;6851.040854280504;6942.722291108501;6967.09427774386;5498.505134537911;6505.837354066186;6528.347165283433;6568.343165683394;6674.032265321598;4676.953230467651;5674.886502270014;5727.656340619576;6429.8392540185;6542.018697195268;6298.779542715983;6366.6180029198395;6409.166808314889;6555.6066636002;6578.375054369827;6488.448481878904;6523;6536;6658.3674550917585;6697.665116744046;6362.172917520693;6521.217453905561;6523.956167013255;6527.608343499405;6528.869422611616 12:28:02 INFO - PID 2476 | |1;ext.html;813.4062134641712;855.5209082913569;918.1415376622854;921.6497730862271;930.9860352094718;35525.934221973315;37643.623563764355;38764.41853372202;38774.61225387745;38793.806030969805;32917.83541082291;37744.622553774454;38742.41886371707;39004.219915601745;39115;7726.188750181221;7782.182870798541;7857.607119644023;7871.8425631487335;7875.763727088168;7575.583342916138;7643.3885289177015;7734.8453030939345;7801.726939557117;7805.258500442477;6430.678466076701;7611.086669599628;7714.8457030859345;7800.10298815635;7809.140994490602;7568.432367572426;7963.72126975556;8385.329173666092;8390.244877961013;8402.957985210034;8023.676093444575;8208;8213.753587392388;8268.793280168044;8272.669093236264;35820.64179358199;36196.819015905014;36220.63779362199;36307.4553881692;36333.63666363355;3422.110251334646;3475.287566048979;3493.406120959443;3501.1072176594807;3531.5762108546883;28866;29617.259568510744;29900.55149172764;29926.40147197044;30357.544636830466;2545.949081018388;2839.531477306242;3018.351054523266;3025.0471101602925;3045.7867949243564;295649.0435095643;304950.47524762456;306579;309556;313074;46787.76606117016;47231.291530627066;47723.761381192875;47940;48256.517434825204;39446.80276598599;49049.75475122673;49255.75372123188;50211.995760084596;50494.495055049716;14055.22696251716;14287.57137285883;14315.928420357832;14337.64155896111;14351.210683412304;11425.371604561662;11737.295762254293;11741.062724650508;11776;11799.351035692947;13134.34328283582;13170.802437963439;13242.271675057771;13332.800007999887;13367.532136375376;5544.500994910397;5626.521745651584;5652.85867853307;5686.715664216773;5713.600167958876;915.3318077803241;922.9676961306322;935.9391639543408;979.363413781048;980.1374790184734;19450.610987780165;20823;23136.190233341753;23659.52680946406;23999.880000599886;943.6932996775938;964.8408012677829;1028.7633844215873;1045.2474218562622;1070.2454769387564;10132.594696212069;10852.131829453625;11773.352465614302;12022.27866328023;12095.092868034933;277415;337259;342387;349443;350106;50615.7469212659;50746;50883.745581271854;50990.49009509932;51097.48902510927;1042.6246551241547;1336.251699048536;1419.858014198572;1455.7088582283375;1464.655805885616;21914.013869376235;22222.111115555195;22347.66478502791;22583.09667613277;23213.071477140726;26778.866105669345;35390.46914296236;35415;35576.7548135814;35652.82173589116;293436.0656393409;293992.53003734845;295233;295597;300416;28091.016814411396;29224.83100675949;29356;32141.517877232327;32458.188545286557;35985.92042238737;36635.63364366322;39450.2109957805;40059.1988160241;40708.79645601753;21644.567108658055;24963.375915602257;26577.867110664323;27800.304992374542;28630;18039.819601803814;21804.345869623936;29349.706502934696;29553.965611203505;29873.70126298709;37798.43302350522;37860;37886.62113378831;37923.81038094902;37968;26799.86600066987;27428.58857117105;27709.168724938285;29285.121446356643;29291.41417171687;19088.713669295248;22448.663270050634;22870.42823929415;23960.041598335873;23965.760342396352 12:28:02 INFO - PID 2476 | |2;jquery.html;561.4778256221714;562.2381672833311;565.5193085877005;566.4929887750466;576.6684156609949;22427.77572224277;22927.656085158716;23031.654525182093;23210;23222.883885580548;21719.782802171972;23564.410889727766;24407.633885491687;25619.975200991983;25935.481290374228;6756.054152418666;6909.378155965962;6915.861682766367;6923.792286231421;6924.723011079551;5624.437556244384;6812.14848143982;6875.449964002868;6881.243063263037;6904;6149.569530132892;6390.71241794118;6546.509011824109;6820.7805120556795;6832.795016149498;6549.508786840982;6553.508486863504;6572.868542629169;6671.432928201109;6680.632565208912;4757.26262429324;5795.652260864362;6155.476784473325;6158.414950579732;6165.445109940127;30860;31179.84410077935;31404.685953140404;31760.047198584078;31812.84093579517;2184.563087382514;2186.7922547357975;2224.89987950541;2233.899474523634;2254.222293308806;19090;19377.224911003545;19378;19627.411177664693;19734.506637334038;1773.5300145461463;2130.8934553272275;2227.9665805012937;2264.9207277745363;2273.931782046525;154484;193908.0609193904;194021.02989485103;199132;200039;15640.530784076496;15757.290921908541;16091.597710057342;16161.919190404207;16359.918200408922;22318.442038949157;22632.755198463914;22982.425439363815;23017.654735178985;23020.4244893879;7926.524408535506;9895.158911492605;9985.800283994426;10072.798544029078;10202.897971020344;8824.5587720615;8975.28197744192;9115.772105697411;9126;9167.083291670913;7899.5260284381975;9419;9551.617935282511;9702.320837541305;9782.608695652094;2395.4490467192645;2555.9744402556107;2702.513547561458;2759.530879750447;2920.707929207062;904.8326059678981;935.4153653966271;941.6280569175275;962.3215632978734;1004.2819384140374;14094.718105638034;19774.90112549428;21440.678389824167;22064.88967555184;22107.447313817283;953.6376177052675;983.4689267795453;985.7338518599938;1016.7915577306783;1019.5768755966191;10584.364938103738;11147.498362573704;11148.554057837759;11179.552817887194;11525;538152.3092384591;565262;571009;593733;600467;154061;154630;157041.42958570496;157532.21233893986;158246;1127.5715228213226;1152.1013609384693;1167.0896700573567;1169.368540987874;1177.5937301630925;18332.450026499228;18901.716474252622;19638.509037274183;19798.802011979697;19888.70723402974;64891.35108648853;66031.33968660251;68431.65784171048;68641;68719.96920046103;85502.57248713716;86470;87225;89921.55039225024;91033.08966910247;9700.805983880424;12024.699382515157;12058.276503409823;12217.51129954792;12268.693282667648;13776.96672749568;17159.828401715822;17303.91348043252;17600.94394336344;17743.201555929754;11769.882301177222;11798.056155507367;11804.17370784037;11858.229215101335;11955.923966843025;9824.607015719292;11713.355765432983;11851;12405.44175512086;12921.483140674643;11401.714957126138;14329.068610540282;14465.855341446451;14502.492412765503;14512.927435363177;11881.524739010689;12098.274103553815;12139.332336721565;12153.149279550347;12160.513579456723;11133.053690436403;13507.864921351054;13622.795658064939;13831.893448524295;13876.306184690928 12:28:02 INFO - PID 2476 | |3;mootools.html;1313.6863136863137;1336.4253371050445;1384.6953670192556;1441.0633088492473;1447.6163816588605;14723.116613003227;15327.233638318081;15349.76975345368;15383.230838458088;15405.691886162298;9875.308728389016;12931.094823362368;13018.869811301884;14617.634559136;15045.623859403493;2761.585762135686;2813.535766598509;2856.057501024657;2877.0649538899943;2885.552739325408;2781.9304517387127;2832.7167283271715;2838.801283910127;2859.8999035033776;2884.5096333623333;2747.4230411613585;2753.7796976241852;2823.68939416664;2851.2586727450803;2853.529167687329;2608.1393140263763;2669.532831754445;2674.5720684690455;2791.5812628105746;2801.4677211329986;2206.470447092702;2852.614896988901;2865.8280503169876;2922.7954043216982;2930.3113768264443;15004.099754014795;15160.545183644397;15185.924070379688;15197.936144469892;15209.61975950599;6957.5129740918155;8206.753797386087;8469.36479764014;8475.576221188916;8502.702405415781;4046.291898917678;4921.4094308682825;5227.163653815372;5270.077736396153;5282.683039017633;1393.4147657983672;1446.03115912339;1466.0397439677029;1479.7558402863515;1490.7117574523054;22912.54174916509;23308.65037024446;23424.64863027056;23425.180118695935;23605.409864753346;6987.790366289019;7031.964840175767;7145.249748776408;7251.746188883364;7320.267973202637;15538.533843984698;15588.376464941275;15955.563999240125;15981.121038343003;16000.839991600167;7955.681772729026;8118.10700822909;8119.066307374609;8127.634256458474;8134.918650813534;5400.540954018874;7138.500304978602;7169.175544812309;7343.228960959129;7487.7004919803685;5296.549793267538;5421.701806400686;5518.862028449321;5522.0336441122645;5534.806281780118;1336.8930485561327;1596.6966276407445;1608.8471595198437;1616.9974615738317;1628.3730763656079;792.1563534835382;803.1687203744096;806.1173015547965;821.2772759971187;827.611022819274;7290.9635451822405;7535.660895259617;7641.579713115721;7692.499987500794;8058.516489010678;682.5154140560159;692.2696555134281;700.1108592088019;704.3625518905399;712.8396110875011;3788;4171.290880550314;4731.810727570927;5003.499650034967;5180.32655754757;14091.929540352232;20135.993200339923;20464.488387790127;20484.795152048588;20555.486112847;17626.85425447342;18141.546461338574;19900.90049549743;20235;20463.97680116018;9665.420074795535;10031.69904902854;10179.236557258087;10182.23633227511;10186.541605627617;7395.334419902019;8138.511689298661;8267.83464330722;8495.575221239036;8697.173768491983;13689.520866769615;17851.821481785017;18296.90851545787;18506;18534.907325463286;17565.297388104333;18008.459746207634;18815.153318100445;19030.33393831209;19081.90459047696;11461.312321260752;11826.113041521749;11882.28706277626;12033.578824741442;12142.878571214174;11486.827697584376;14084.647883802987;14183.149011058957;14211.431542738175;14220.933429993;7988.880166797619;10126.544305506417;10248.436336001592;10356.948215259177;10845.945770271099;9970.351927124713;10126.696199114038;10133.493325333851;10300;10451.216158788273;9211.217046551119;9255.722328330161;9309.162175404246;9443.811123777348;9464.95885452586;6026.5383348143;6467.482601391972;6551.868962620816;6841.486888483483;7120.501564890408;6803.659817009228;6936.826579335355;7061.082059332254;7161.9641901790155;7171.282871712787 12:28:02 INFO - PID 2476 | |4;prototype.html;450.8309383981007;462.81057681757875;483.258198665049;496.6126421391315;500.07486150621355;4564.657650676196;6026.246719160105;6182.258129024518;6220.117207991721;6306.621602703841;5822.7088645567765;5897.616654917428;6020.45815876571;6076.574639775217;6100.603460775046;3622.492851000862;4538.341940418637;4601.217792975185;4604.536572933482;4614.146382919154;3257.5928008998876;4637.814487420499;4655.790489427984;4660.786126589876;4666.883327916796;4534.546545345455;4588.655850811186;4628.44458664959;4634.2353511670535;4636.814527418899;3686.391745362012;3976.542697589771;4255.319148936186;4596.54034596541;4604;3718.1819999600316;4042.292598795199;4048.736832105904;4053.9189216215805;4080.5511393746665;5800.738966746507;5887.293524777054;5898.734556944948;5901.911471327933;5911;4304.892377690551;4856.761525810895;4866.294387313855;5535.750891209906;5561.582881283879;6407.359264073602;6527;6598.7030583623855;6606.042123892009;6621.437177839889;4567.634589232854;4658.836940707059;4665.996810685719;4668.836590719343;4674.228752255874;6002.378168810035;6819.488538359593;6896.586204827727;6956.930430695679;7018.263082376328;3645.434957581579;4693.545001049685;4826.95173048272;4830.758462076882;4853.907870729057;4585.656075794329;4827.879303017453;4865.172920603506;4937.259411088366;4939.604831613462;5457.890842183134;5461.1262198048125;5549.6115271931385;5557.722113894289;5570.41510641385;5637.830865074055;6389.9680501597195;6478.514111441661;6541.771038013647;6544.541882068304;1651.3229575873834;1657.4364715996378;1661.7008938390964;1686.1316422042657;1692.213120898788;1053.4311471805293;1141.1840534018218;1146.9311841289382;1147.5811328864904;1151.9884801151882;4811.759412029454;5950.732217050243;6001.669908155093;6015.30823955242;6050.667213303311;5815.680137592389;6152.815415537541;6522.412982831568;6689.565178263398;6703.240699103593;5496.31296087989;5912.556558258148;5963.761449542057;6011.609245399035;6020.759169633166;3067.8188897274263;3107.5960125184033;3427.8628854846024;3492;3513.631068737746;5599.944000559943;6220.440160385587;6362.236531616143;6791.185057793096;6855.3845539055055;6885.621290828953;6921.653917304115;6991.23096459389;7022.367986881103;7026.26224246457;6775.424088952395;6836.589804611739;6936.306369363023;6945.340192681687;6959.164900212007;5298.894022119459;6220.471259942775;6620.66896655175;6643.202815662152;6769.1876974763345;5780.710964451845;6359.534053940521;6473.514486413444;6625.4699624029;6676.365745254193;6300.991841305369;6367.681615919279;6581;6859.691313890787;6944.895826562504;4091.6112969267874;4231.217224813419;4493.505714371482;4603.654725895638;4771.78319528518;6683;6689.09697190873;6781.42357899584;6814.250432452525;6950.6177160256675;5111.974440127776;6246.406591373812;6409.262934762562;6463.57986730861;6503.25212600557;5682.6306290091015;5827.24245848037;6299.842503937438;6557.868842623026;6597.835054123685;5048.823291184791;5287.7620507078045;6217.222847144107;6241.625502469867;6467.450266727381;6411.166548348685;6419.876521608607;6569.605823650596;6599;6613.801585952237;5511.4213007633625;5512.6968016759465;6240.28236752761;6376.936230637634;6416.2300523935555 12:28:02 INFO - PID 2476 | |5;yui.html;766.6894907562437;837.2130197614246;866.9479831210132;875.277895736018;879.1560102301788;16947.406840760577;17420.216090275935;17420.65158696825;17431.21559529821;19031.904840475814;18357.17392717331;18696.626067478675;18835.340763073295;18940.052997350147;19039.52401189971;936.7189843047092;1038.5638032026568;1040.1990467340142;1040.583766493405;1061.1086687182765;971.3249291742242;1000.4697510319543;1015.228426395937;1021.9131373833233;1032.917366610673;1052.4842827014745;1055.208593554834;1079.3631757263213;1086.3373342261214;1092.6066616018227;965.2760429677742;1050.296301478004;1077.391273930231;1080.7460246841986;1098.774751176007;774.3959711425098;791.7149826062613;792.2196636313226;798.8641930871765;802.4022103532906;17102.31590736369;17411;17660.02869842158;17777.46667599974;17801.287948482044;16824.663506729918;16948.915255423643;17829.10854457272;17944.371946981923;17945.641087178316;14047.719045619031;14657;14698.971072024962;14712.632184195374;14804.629884252872;2741.191348552182;3609.2781443711365;3621.257642183346;3672.265546890606;3696.2792255510185;146840;152679.23660381738;154451;160289;161306.19346903308;11107.722306942229;13653.590392288248;13780.310984450738;13918.652033699238;13982.161070335813;20890.686639700416;26939.461210776066;27258.72741272562;27491.31271718223;27712.584311235347;7836;7952.562609056557;8119.3910456715985;8148.144444833326;8194.09864914859;3443.776154549946;3920.725549211528;3946.8026598670526;4030.979845100756;4056.6146216109423;4491.977540112344;4583.312503124558;5260.579153667697;5564.332280126329;5682.664573825148;1107.3189988157226;1123.3260043973714;1127.7575321305958;1139.407508095802;1141.303804679145;560.9986174678261;598.8023952095798;612.1919066831829;636.8407898025494;639.7377075399064;9327.673531426368;10742.841181722531;11140.777184456265;11477.713057173638;12544.372781360895;574.4800955135609;627.8555932135635;639.7153266796231;656.9146011018537;658.7002913674266;5078.339815823919;5245.1345527988215;5347.41178470368;5503.257060296809;5734.971325143348;127220.36389817992;133520;136156;136774.3161284207;140253;113294.43352783183;115039;116183.41908290405;117739;121030.3948480252;95661.52169239109;100998;103632;105600.88798223993;106141.46929265458;12136.089793265364;12210.572629958264;12291.44688489038;12317.322547259986;12802.807957880825;50590.49409505858;51875.74062129665;52161.47838521566;52173.43479695615;52265.21602175894;68410.65794670995;69312.30687693058;71111.28888711253;72005.6399717998;72220.63889680729;16977.575560611083;17135.914320428317;17196.398126065527;17247.65504689874;17284.481465556055;17338.566535836704;17534.298628055247;17727;18150.364737234133;18562.628747425246;15237.085774853544;15571.688566228839;17139;17141.48575542736;17159.485215443055;16821.2430440628;17140.914295428443;17213;17316.82683173152;17424.82575174232;16341.836581634032;16802.411915582896;16879.662406752042;16989.66020679604;17381.82618173853;11771.470283837076;11811.409429528661;12381;12811.679708007374;12947.5468358607;12557.183783054072;12615.936920315708;12677.366131693563;12924.612261632166;13185.60443186706 12:28:02 INFO - PID 2476 | __end_tp_report 12:28:02 INFO - PID 2476 | __start_cc_report 12:28:02 INFO - PID 2476 | _x_x_mozilla_cycle_collect,445 12:28:02 INFO - PID 2476 | __end_cc_report 12:28:02 INFO - PID 2476 | __startTimestamp1498073282933__endTimestamp 12:28:02 INFO - PID 2476 | 12:28:02 INFO - PID 2476 | ------- Summary: start ------- 12:28:02 INFO - PID 2476 | Number of tests: 6 12:28:02 INFO - PID 2476 | 12:28:02 INFO - PID 2476 | [#0] dojo.html Cycles:165 Average:6226.87 Median:6483.12 stddev:769.49 (11.9%) stddev-sans-first:743.26 12:28:02 INFO - PID 2476 | Values: 3578.1 3592.5 3617.9 3641.8 3642.4 6364.1 6403.0 6431.2 6454.9 6471.4 6447.7 6462.9 6474.2 6483.1 6485.9 5606.1 5614.0 5647.6 5766.1 5779.8 5483.5 5665.8 5673.8 5678.3 5703.1 5530.0 5566.1 5580.6 5582.6 5600.4 5526.6 5582.1 5589.7 5601.6 5620.9 4840.0 5485.3 5630.8 5635.1 5691.7 5285.7 5927.0 6432.8 6453.8 6478.3 5824.7 6929.2 7023.4 7036.9 7067.4 6835.6 6848.8 6870.5 6906.6 6969.7 6351.2 6424.4 6425.9 6497.6 6517.4 7018.9 7084.8 7116.8 7126.8 7139.0 6633.8 6638.5 6749.4 6783.5 6786.9 6562.1 6945.7 6960.1 6964.8 6968.4 6611.8 6650.3 6701.2 6703.0 6712.6 5950.4 6691.2 6696.1 6818.9 6867.5 4490.6 5172.6 5241.9 5365.1 5375.3 4555.8 4713.8 4716.2 4789.2 4854.6 6082.3 6127.2 6235.4 6259.9 6260.9 6669.7 6781.4 6804.7 6813.1 6902.3 6114.1 6181.7 6215.1 6227.3 6239.7 5790.8 5891.9 5897.1 5917.0 5936.7 6838.8 6854.4 6874.7 6931.8 7066.9 6520.2 6760.4 6764.9 6789.0 6806.1 6874.1 6884.7 7037.2 7086.6 7100.3 6504.5 6684.8 6780.3 6821.9 6841.4 6772.6 6829.9 6851.0 6942.7 6967.1 5498.5 6505.8 6528.3 6568.3 6674.0 4677.0 5674.9 5727.7 6429.8 6542.0 6298.8 6366.6 6409.2 6555.6 6578.4 6488.4 6523.0 6536.0 6658.4 6697.7 6362.2 6521.2 6524.0 6527.6 6528.9 12:28:02 INFO - PID 2476 | 12:28:02 INFO - PID 2476 | [#1] ext.html Cycles:180 Average:44833.06 Median:176468.20 stddev:82071.03 (46.5%) stddev-sans-first:82234.71 12:28:02 INFO - PID 2476 | Values: 813.4 855.5 918.1 921.6 931.0 35525.9 37643.6 38764.4 38774.6 38793.8 32917.8 37744.6 38742.4 39004.2 39115.0 7726.2 7782.2 7857.6 7871.8 7875.8 7575.6 7643.4 7734.8 7801.7 7805.3 6430.7 7611.1 7714.8 7800.1 7809.1 7568.4 7963.7 8385.3 8390.2 8403.0 8023.7 8208.0 8213.8 8268.8 8272.7 35820.6 36196.8 36220.6 36307.5 36333.6 3422.1 3475.3 3493.4 3501.1 3531.6 28866.0 29617.3 29900.6 29926.4 30357.5 2545.9 2839.5 3018.4 3025.0 3045.8 295649.0 304950.5 306579.0 309556.0 313074.0 46787.8 47231.3 47723.8 47940.0 48256.5 39446.8 49049.8 49255.8 50212.0 50494.5 14055.2 14287.6 14315.9 14337.6 14351.2 11425.4 11737.3 11741.1 11776.0 11799.4 13134.3 13170.8 13242.3 13332.8 13367.5 5544.5 5626.5 5652.9 5686.7 5713.6 915.3 923.0 935.9 979.4 980.1 19450.6 20823.0 23136.2 23659.5 23999.9 943.7 964.8 1028.8 1045.2 1070.2 10132.6 10852.1 11773.4 12022.3 12095.1 277415.0 337259.0 342387.0 349443.0 350106.0 50615.7 50746.0 50883.7 50990.5 51097.5 1042.6 1336.3 1419.9 1455.7 1464.7 21914.0 22222.1 22347.7 22583.1 23213.1 26778.9 35390.5 35415.0 35576.8 35652.8 293436.1 293992.5 295233.0 295597.0 300416.0 28091.0 29224.8 29356.0 32141.5 32458.2 35985.9 36635.6 39450.2 40059.2 40708.8 21644.6 24963.4 26577.9 27800.3 28630.0 18039.8 21804.3 29349.7 29554.0 29873.7 37798.4 37860.0 37886.6 37923.8 37968.0 26799.9 27428.6 27709.2 29285.1 29291.4 19088.7 22448.7 22870.4 23960.0 23965.8 12:28:02 INFO - PID 2476 | 12:28:02 INFO - PID 2476 | [#2] jquery.html Cycles:180 Average:39529.22 Median:2269.43 stddev:99122.63 (4367.7%) stddev-sans-first:99357.52 12:28:02 INFO - PID 2476 | Values: 561.5 562.2 565.5 566.5 576.7 22427.8 22927.7 23031.7 23210.0 23222.9 21719.8 23564.4 24407.6 25620.0 25935.5 6756.1 6909.4 6915.9 6923.8 6924.7 5624.4 6812.1 6875.4 6881.2 6904.0 6149.6 6390.7 6546.5 6820.8 6832.8 6549.5 6553.5 6572.9 6671.4 6680.6 4757.3 5795.7 6155.5 6158.4 6165.4 30860.0 31179.8 31404.7 31760.0 31812.8 2184.6 2186.8 2224.9 2233.9 2254.2 19090.0 19377.2 19378.0 19627.4 19734.5 1773.5 2130.9 2228.0 2264.9 2273.9 154484.0 193908.1 194021.0 199132.0 200039.0 15640.5 15757.3 16091.6 16161.9 16359.9 22318.4 22632.8 22982.4 23017.7 23020.4 7926.5 9895.2 9985.8 10072.8 10202.9 8824.6 8975.3 9115.8 9126.0 9167.1 7899.5 9419.0 9551.6 9702.3 9782.6 2395.4 2556.0 2702.5 2759.5 2920.7 904.8 935.4 941.6 962.3 1004.3 14094.7 19774.9 21440.7 22064.9 22107.4 953.6 983.5 985.7 1016.8 1019.6 10584.4 11147.5 11148.6 11179.6 11525.0 538152.3 565262.0 571009.0 593733.0 600467.0 154061.0 154630.0 157041.4 157532.2 158246.0 1127.6 1152.1 1167.1 1169.4 1177.6 18332.5 18901.7 19638.5 19798.8 19888.7 64891.4 66031.3 68431.7 68641.0 68720.0 85502.6 86470.0 87225.0 89921.6 91033.1 9700.8 12024.7 12058.3 12217.5 12268.7 13777.0 17159.8 17303.9 17600.9 17743.2 11769.9 11798.1 11804.2 11858.2 11955.9 9824.6 11713.4 11851.0 12405.4 12921.5 11401.7 14329.1 14465.9 14502.5 14512.9 11881.5 12098.3 12139.3 12153.1 12160.5 11133.1 13507.9 13622.8 13831.9 13876.3 12:28:02 INFO - PID 2476 | 12:28:02 INFO - PID 2476 | [#3] mootools.html Cycles:180 Average:8792.00 Median:2807.50 stddev:6105.53 (217.5%) stddev-sans-first:6096.80 12:28:02 INFO - PID 2476 | Values: 1313.7 1336.4 1384.7 1441.1 1447.6 14723.1 15327.2 15349.8 15383.2 15405.7 9875.3 12931.1 13018.9 14617.6 15045.6 2761.6 2813.5 2856.1 2877.1 2885.6 2781.9 2832.7 2838.8 2859.9 2884.5 2747.4 2753.8 2823.7 2851.3 2853.5 2608.1 2669.5 2674.6 2791.6 2801.5 2206.5 2852.6 2865.8 2922.8 2930.3 15004.1 15160.5 15185.9 15197.9 15209.6 6957.5 8206.8 8469.4 8475.6 8502.7 4046.3 4921.4 5227.2 5270.1 5282.7 1393.4 1446.0 1466.0 1479.8 1490.7 22912.5 23308.7 23424.6 23425.2 23605.4 6987.8 7032.0 7145.2 7251.7 7320.3 15538.5 15588.4 15955.6 15981.1 16000.8 7955.7 8118.1 8119.1 8127.6 8134.9 5400.5 7138.5 7169.2 7343.2 7487.7 5296.5 5421.7 5518.9 5522.0 5534.8 1336.9 1596.7 1608.8 1617.0 1628.4 792.2 803.2 806.1 821.3 827.6 7291.0 7535.7 7641.6 7692.5 8058.5 682.5 692.3 700.1 704.4 712.8 3788.0 4171.3 4731.8 5003.5 5180.3 14091.9 20136.0 20464.5 20484.8 20555.5 17626.9 18141.5 19900.9 20235.0 20464.0 9665.4 10031.7 10179.2 10182.2 10186.5 7395.3 8138.5 8267.8 8495.6 8697.2 13689.5 17851.8 18296.9 18506.0 18534.9 17565.3 18008.5 18815.2 19030.3 19081.9 11461.3 11826.1 11882.3 12033.6 12142.9 11486.8 14084.6 14183.1 14211.4 14220.9 7988.9 10126.5 10248.4 10356.9 10845.9 9970.4 10126.7 10133.5 10300.0 10451.2 9211.2 9255.7 9309.2 9443.8 9465.0 6026.5 6467.5 6551.9 6841.5 7120.5 6803.7 6936.8 7061.1 7162.0 7171.3 12:28:02 INFO - PID 2476 | 12:28:02 INFO - PID 2476 | [#4] prototype.html Cycles:180 Average:5267.11 Median:5824.98 stddev:1607.76 (27.6%) stddev-sans-first:1571.10 12:28:02 INFO - PID 2476 | Values: 450.8 462.8 483.3 496.6 500.1 4564.7 6026.2 6182.3 6220.1 6306.6 5822.7 5897.6 6020.5 6076.6 6100.6 3622.5 4538.3 4601.2 4604.5 4614.1 3257.6 4637.8 4655.8 4660.8 4666.9 4534.5 4588.7 4628.4 4634.2 4636.8 3686.4 3976.5 4255.3 4596.5 4604.0 3718.2 4042.3 4048.7 4053.9 4080.6 5800.7 5887.3 5898.7 5901.9 5911.0 4304.9 4856.8 4866.3 5535.8 5561.6 6407.4 6527.0 6598.7 6606.0 6621.4 4567.6 4658.8 4666.0 4668.8 4674.2 6002.4 6819.5 6896.6 6956.9 7018.3 3645.4 4693.5 4827.0 4830.8 4853.9 4585.7 4827.9 4865.2 4937.3 4939.6 5457.9 5461.1 5549.6 5557.7 5570.4 5637.8 6390.0 6478.5 6541.8 6544.5 1651.3 1657.4 1661.7 1686.1 1692.2 1053.4 1141.2 1146.9 1147.6 1152.0 4811.8 5950.7 6001.7 6015.3 6050.7 5815.7 6152.8 6522.4 6689.6 6703.2 5496.3 5912.6 5963.8 6011.6 6020.8 3067.8 3107.6 3427.9 3492.0 3513.6 5599.9 6220.4 6362.2 6791.2 6855.4 6885.6 6921.7 6991.2 7022.4 7026.3 6775.4 6836.6 6936.3 6945.3 6959.2 5298.9 6220.5 6620.7 6643.2 6769.2 5780.7 6359.5 6473.5 6625.5 6676.4 6301.0 6367.7 6581.0 6859.7 6944.9 4091.6 4231.2 4493.5 4603.7 4771.8 6683.0 6689.1 6781.4 6814.3 6950.6 5112.0 6246.4 6409.3 6463.6 6503.3 5682.6 5827.2 6299.8 6557.9 6597.8 5048.8 5287.8 6217.2 6241.6 6467.5 6411.2 6419.9 6569.6 6599.0 6613.8 5511.4 5512.7 6240.3 6376.9 6416.2 12:28:02 INFO - PID 2476 | 12:28:02 INFO - PID 2476 | [#5] yui.html Cycles:180 Average:25692.54 Median:17300.65 stddev:39149.27 (226.3%) stddev-sans-first:39214.36 12:28:02 INFO - PID 2476 | Values: 766.7 837.2 866.9 875.3 879.2 16947.4 17420.2 17420.7 17431.2 19031.9 18357.2 18696.6 18835.3 18940.1 19039.5 936.7 1038.6 1040.2 1040.6 1061.1 971.3 1000.5 1015.2 1021.9 1032.9 1052.5 1055.2 1079.4 1086.3 1092.6 965.3 1050.3 1077.4 1080.7 1098.8 774.4 791.7 792.2 798.9 802.4 17102.3 17411.0 17660.0 17777.5 17801.3 16824.7 16948.9 17829.1 17944.4 17945.6 14047.7 14657.0 14699.0 14712.6 14804.6 2741.2 3609.3 3621.3 3672.3 3696.3 146840.0 152679.2 154451.0 160289.0 161306.2 11107.7 13653.6 13780.3 13918.7 13982.2 20890.7 26939.5 27258.7 27491.3 27712.6 7836.0 7952.6 8119.4 8148.1 8194.1 3443.8 3920.7 3946.8 4031.0 4056.6 4492.0 4583.3 5260.6 5564.3 5682.7 1107.3 1123.3 1127.8 1139.4 1141.3 561.0 598.8 612.2 636.8 639.7 9327.7 10742.8 11140.8 11477.7 12544.4 574.5 627.9 639.7 656.9 658.7 5078.3 5245.1 5347.4 5503.3 5735.0 127220.4 133520.0 136156.0 136774.3 140253.0 113294.4 115039.0 116183.4 117739.0 121030.4 95661.5 100998.0 103632.0 105600.9 106141.5 12136.1 12210.6 12291.4 12317.3 12802.8 50590.5 51875.7 52161.5 52173.4 52265.2 68410.7 69312.3 71111.3 72005.6 72220.6 16977.6 17135.9 17196.4 17247.7 17284.5 17338.6 17534.3 17727.0 18150.4 18562.6 15237.1 15571.7 17139.0 17141.5 17159.5 16821.2 17140.9 17213.0 17316.8 17424.8 16341.8 16802.4 16879.7 16989.7 17381.8 11771.5 11811.4 12381.0 12811.7 12947.5 12557.2 12615.9 12677.4 12924.6 13185.6 12:28:02 INFO - PID 2476 | -------- Summary: end -------- 12:28:02 INFO - PID 2476 | 12:28:03 INFO - TEST-INFO | 2476: exit 0 12:28:03 INFO - TEST-OK | dromaeo_css | took 1117437ms 12:28:03 INFO - TEST-START | kraken 12:28:03 INFO - operating with platform_type : mac_ 12:28:03 INFO - Initialising browser for kraken test... 12:28:03 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:57176/getInfo.html -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpMxtR5w/profile 12:28:03 INFO - TEST-INFO | started process 2481 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:57176/getInfo.html -foreground) 12:28:08 INFO - TEST-INFO | 2481: exit 0 12:28:08 INFO - Browser initialized. 12:28:08 INFO - Running cycle 1/1 for kraken test... 12:28:08 INFO - TEST-INFO | started process 2487 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpMxtR5w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:28:10 INFO - PID 2487 | 2017-06-21 12:28:10.877 plugin-container[2488:32653] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 12:28:10 INFO - PID 2487 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:10 INFO - PID 2487 | 2017-06-21 12:28:10.878 plugin-container[2488:32653] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4527, name = 'com.apple.CFPasteboardClient' 12:28:10 INFO - PID 2487 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:10 INFO - PID 2487 | 2017-06-21 12:28:10.878 plugin-container[2488:32653] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:28:11 INFO - PID 2487 | 2017-06-21 12:28:11.361 plugin-container[2489:32706] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 12:28:11 INFO - PID 2487 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:11 INFO - PID 2487 | 2017-06-21 12:28:11.361 plugin-container[2489:32706] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 12:28:11 INFO - PID 2487 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:11 INFO - PID 2487 | 2017-06-21 12:28:11.361 plugin-container[2489:32706] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:28:20 INFO - PID 2487 | RSS: Main: 249380864 12:28:20 INFO - PID 2487 | 12:28:23 INFO - PID 2487 | Cycle 1(1): loaded http://localhost:57176/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57176/tests/kraken/driver.html?testName=audio-beat-detection) 12:28:24 INFO - PID 2487 | RSS: Main: 260202496 12:28:24 INFO - PID 2487 | 12:28:27 INFO - PID 2487 | Cycle 1(1): loaded http://localhost:57176/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57176/tests/kraken/driver.html?testName=audio-dft) 12:28:28 INFO - PID 2487 | RSS: Main: 270827520 12:28:28 INFO - PID 2487 | 12:28:31 INFO - PID 2487 | Cycle 1(1): loaded http://localhost:57176/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57176/tests/kraken/driver.html?testName=audio-fft) 12:28:32 INFO - PID 2487 | RSS: Main: 273670144 12:28:32 INFO - PID 2487 | 12:28:34 INFO - PID 2487 | Cycle 1(1): loaded http://localhost:57176/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57176/tests/kraken/driver.html?testName=audio-oscillator) 12:28:34 INFO - PID 2487 | RSS: Main: 275066880 12:28:34 INFO - PID 2487 | 12:28:36 INFO - PID 2487 | Cycle 1(1): loaded http://localhost:57176/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57176/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:28:37 INFO - PID 2487 | RSS: Main: 276815872 12:28:37 INFO - PID 2487 | 12:28:40 INFO - PID 2487 | Cycle 1(1): loaded http://localhost:57176/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57176/tests/kraken/driver.html?testName=imaging-darkroom) 12:28:40 INFO - PID 2487 | RSS: Main: 284663808 12:28:40 INFO - PID 2487 | 12:28:43 INFO - PID 2487 | Cycle 1(1): loaded http://localhost:57176/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57176/tests/kraken/driver.html?testName=imaging-desaturate) 12:28:44 INFO - PID 2487 | RSS: Main: 284790784 12:28:44 INFO - PID 2487 | 12:28:47 INFO - PID 2487 | Cycle 1(1): loaded http://localhost:57176/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57176/tests/kraken/driver.html?testName=json-parse-financial) 12:28:47 INFO - PID 2487 | RSS: Main: 284585984 12:28:47 INFO - PID 2487 | 12:28:49 INFO - PID 2487 | Cycle 1(1): loaded http://localhost:57176/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57176/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:28:50 INFO - PID 2487 | RSS: Main: 284246016 12:28:50 INFO - PID 2487 | 12:28:51 INFO - PID 2487 | Cycle 1(1): loaded http://localhost:57176/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57176/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:28:52 INFO - PID 2487 | RSS: Main: 284471296 12:28:52 INFO - PID 2487 | 12:28:54 INFO - PID 2487 | Cycle 1(1): loaded http://localhost:57176/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57176/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:28:55 INFO - PID 2487 | RSS: Main: 283987968 12:28:55 INFO - PID 2487 | 12:28:58 INFO - PID 2487 | Cycle 1(1): loaded http://localhost:57176/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57176/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:28:59 INFO - PID 2487 | RSS: Main: 284676096 12:28:59 INFO - PID 2487 | 12:29:02 INFO - PID 2487 | Cycle 1(1): loaded http://localhost:57176/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57176/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:29:02 INFO - PID 2487 | RSS: Main: 282177536 12:29:02 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | Cycle 1(1): loaded http://localhost:57176/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57176/tests/kraken/driver.html?testName=ai-astar) 12:29:04 INFO - PID 2487 | RSS: Main: 284082176 12:29:04 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | __start_tp_report 12:29:04 INFO - PID 2487 | _x_x_mozilla_page_load 12:29:04 INFO - PID 2487 | _x_x_mozilla_page_load_details 12:29:04 INFO - PID 2487 | |i|pagename|runs| 12:29:04 INFO - PID 2487 | |0;ai-astar;108;112;119;161;93;131;164;165;121;149 12:29:04 INFO - PID 2487 | |1;audio-beat-detection;148;118;146;148;112;130;111;150;152;115 12:29:04 INFO - PID 2487 | |2;audio-dft;204;204;214;229;233;201;203;204;205;197 12:29:04 INFO - PID 2487 | |3;audio-fft;75;109;123;137;47;50;67;64;121;47 12:29:04 INFO - PID 2487 | |4;audio-oscillator;68;69;112;69;71;85;114;71;75;112 12:29:04 INFO - PID 2487 | |5;imaging-gaussian-blur;94;91;91;93;94;93;126;136;93;107 12:29:04 INFO - PID 2487 | |6;imaging-darkroom;96;97;130;150;99;97;100;98;98;96 12:29:04 INFO - PID 2487 | |7;imaging-desaturate;93;91;93;91;89;92;93;92;91;90 12:29:04 INFO - PID 2487 | |8;json-parse-financial;49;50;51;56;53;56;53;55;53;54 12:29:04 INFO - PID 2487 | |9;json-stringify-tinderbox;61;56;55;59;61;61;57;59;57;56 12:29:04 INFO - PID 2487 | |10;stanford-crypto-aes;81;83;82;82;82;84;82;81;86;81 12:29:04 INFO - PID 2487 | |11;stanford-crypto-ccm;135;140;139;190;136;172;168;170;147;165 12:29:04 INFO - PID 2487 | |12;stanford-crypto-pbkdf2;196;187;201;227;185;202;275;233;186;187 12:29:04 INFO - PID 2487 | |13;stanford-crypto-sha256-iterative;68;66;67;66;68;67;69;65;62;65 12:29:04 INFO - PID 2487 | __end_tp_report 12:29:04 INFO - PID 2487 | __start_cc_report 12:29:04 INFO - PID 2487 | _x_x_mozilla_cycle_collect,1132 12:29:04 INFO - PID 2487 | __end_cc_report 12:29:04 INFO - PID 2487 | __startTimestamp1498073344926__endTimestamp 12:29:04 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | ------- Summary: start ------- 12:29:04 INFO - PID 2487 | Number of tests: 14 12:29:04 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | [#0] ai-astar Cycles:10 Average:132.30 Median:155.00 stddev:25.88 (16.7%) stddev-sans-first:25.92 12:29:04 INFO - PID 2487 | Values: 108.0 112.0 119.0 161.0 93.0 131.0 164.0 165.0 121.0 149.0 12:29:04 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:147.00 stddev:17.49 (11.9%) stddev-sans-first:17.68 12:29:04 INFO - PID 2487 | Values: 148.0 118.0 146.0 148.0 112.0 130.0 111.0 150.0 152.0 115.0 12:29:04 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | [#2] audio-dft Cycles:10 Average:209.40 Median:204.50 stddev:12.18 (6.0%) stddev-sans-first:12.76 12:29:04 INFO - PID 2487 | Values: 204.0 204.0 214.0 229.0 233.0 201.0 203.0 204.0 205.0 197.0 12:29:04 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | [#3] audio-fft Cycles:10 Average:84.00 Median:48.50 stddev:34.94 (72.0%) stddev-sans-first:36.91 12:29:04 INFO - PID 2487 | Values: 75.0 109.0 123.0 137.0 47.0 50.0 67.0 64.0 121.0 47.0 12:29:04 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | [#4] audio-oscillator Cycles:10 Average:84.60 Median:70.00 stddev:19.97 (28.5%) stddev-sans-first:20.26 12:29:04 INFO - PID 2487 | Values: 68.0 69.0 112.0 69.0 71.0 85.0 114.0 71.0 75.0 112.0 12:29:04 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | [#5] imaging-gaussian-blur Cycles:10 Average:101.80 Median:93.00 stddev:16.23 (17.4%) stddev-sans-first:16.96 12:29:04 INFO - PID 2487 | Values: 94.0 91.0 91.0 93.0 94.0 93.0 126.0 136.0 93.0 107.0 12:29:04 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | [#6] imaging-darkroom Cycles:10 Average:106.10 Median:97.00 stddev:18.52 (19.1%) stddev-sans-first:19.28 12:29:04 INFO - PID 2487 | Values: 96.0 97.0 130.0 150.0 99.0 97.0 100.0 98.0 98.0 96.0 12:29:04 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | [#7] imaging-desaturate Cycles:10 Average:91.50 Median:92.00 stddev:1.35 (1.5%) stddev-sans-first:1.32 12:29:04 INFO - PID 2487 | Values: 93.0 91.0 93.0 91.0 89.0 92.0 93.0 92.0 91.0 90.0 12:29:04 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.50 stddev:2.40 (4.5%) stddev-sans-first:2.07 12:29:04 INFO - PID 2487 | Values: 49.0 50.0 51.0 56.0 53.0 56.0 53.0 55.0 53.0 54.0 12:29:04 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | [#9] json-stringify-tinderbox Cycles:10 Average:58.20 Median:59.00 stddev:2.30 (3.9%) stddev-sans-first:2.20 12:29:04 INFO - PID 2487 | Values: 61.0 56.0 55.0 59.0 61.0 61.0 57.0 59.0 57.0 56.0 12:29:04 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:82.00 stddev:1.58 (1.9%) stddev-sans-first:1.59 12:29:04 INFO - PID 2487 | Values: 81.0 83.0 82.0 82.0 82.0 84.0 82.0 81.0 86.0 81.0 12:29:04 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | [#11] stanford-crypto-ccm Cycles:10 Average:156.20 Median:166.50 stddev:19.15 (11.5%) stddev-sans-first:18.71 12:29:04 INFO - PID 2487 | Values: 135.0 140.0 139.0 190.0 136.0 172.0 168.0 170.0 147.0 165.0 12:29:04 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:207.90 Median:201.50 stddev:29.06 (14.4%) stddev-sans-first:30.50 12:29:04 INFO - PID 2487 | Values: 196.0 187.0 201.0 227.0 185.0 202.0 275.0 233.0 186.0 187.0 12:29:04 INFO - PID 2487 | 12:29:04 INFO - PID 2487 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:67.00 stddev:2.00 (3.0%) stddev-sans-first:2.03 12:29:04 INFO - PID 2487 | Values: 68.0 66.0 67.0 66.0 68.0 67.0 69.0 65.0 62.0 65.0 12:29:04 INFO - PID 2487 | -------- Summary: end -------- 12:29:04 INFO - PID 2487 | 12:29:05 INFO - TEST-INFO | 2487: exit 0 12:29:05 INFO - TEST-OK | kraken | took 62218ms 12:29:05 INFO - SUITE-END | took 1179s 12:29:06 INFO - Completed test suite (00:19:40) 12:29:06 INFO - javascript benchmark 12:29:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8280.749243927397, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3578.141246100936, 3592.5150104735885, 3617.945730814037, 3641.7632853864498, 3642.4354225095103, 6364.109024736541, 6402.967985160073, 6431.196100487439, 6454.935450645504, 6471.449926756231, 6447.710457908413, 6462.903056454145, 6474.15835941327, 6483.124778154959, 6485.870282594346, 5606.131049687305, 5614.017546929292, 5647.604667673265, 5766.077427611583, 5779.7688092476465, 5483.479069488392, 5665.773369065253, 5673.773049078052, 5678.318601767794, 5703.116017017348, 5530.032244357221, 5566.137248726413, 5580.581456390787, 5582.637128586629, 5600.411956744565, 5526.585506087019, 5582.134769110794, 5589.664620122765, 5601.6358936668985, 5620.915686264669, 4839.975800120977, 5485.286912701324, 5630.8029218977135, 5635.098384258499, 5691.743871525791, 5285.7357132143625, 5926.97036514819, 6432.839179020562, 6453.83865403364, 6478.2873883872735, 5824.650520968713, 6929.203141638675, 7023.438124949992, 7036.92963070373, 7067.43460523157, 6835.58986460809, 6848.760293389708, 6870.519063665545, 6906.620135892524, 6969.686364113627, 6351.237851457855, 6424.38968298011, 6425.903611445832, 6497.64262965532, 6517.3808488193545, 7018.9298107019295, 7084.787456376317, 7116.786496405116, 7126.786196414012, 7138.964305178545, 6633.800985970429, 6638.535302528873, 6749.392554670005, 6783.491238157059, 6786.864262714718, 6562.114114594564, 6945.687444065029, 6960.095187625576, 6964.756233531802, 6968.372846443743, 6611.834704132435, 6650.301718319507, 6701.1623547056615, 6702.966485167543, 6712.563683360566, 5950.375210602824, 6691.1636045494315, 6696.0960270362875, 6818.931810681929, 6867.450603951672, 4490.640748740093, 5172.629253247892, 5241.868953276198, 5365.141577347684, 5375.301210842644, 4555.794989225493, 4713.764311784397, 4716.174669432836, 4789.209780386267, 4854.563089321978, 6082.300535438481, 6127.20346354971, 6235.407636274546, 6259.906101408483, 6260.874782504325, 6669.733210671616, 6781.38967492928, 6804.65976701163, 6813.080234168423, 6902.309769023058, 6114.11489084418, 6181.690915454209, 6215.12988181655, 6227.314995350508, 6239.688015599202, 5790.826275211751, 5891.941080589139, 5897.051474262868, 5916.9941110011405, 5936.673482958392, 6838.760643377458, 6854.383105520527, 6874.65626718652, 6931.757388491378, 7066.858662826818, 6520.152380190545, 6760.425363844129, 6764.86470270582, 6789.049533065474, 6806.149231346082, 6874.072000279898, 6884.724611015503, 7037.1555413350725, 7086.645667716491, 7100.254473280438, 6504.479641628753, 6684.83287917806, 6780.32196780318, 6821.897671534832, 6841.3842754152365, 6772.55978361405, 6829.941359089349, 6851.040854280504, 6942.722291108501, 6967.09427774386, 5498.505134537911, 6505.837354066186, 6528.347165283433, 6568.343165683394, 6674.032265321598, 4676.953230467651, 5674.886502270014, 5727.656340619576, 6429.8392540185, 6542.018697195268, 6298.779542715983, 6366.6180029198395, 6409.166808314889, 6555.6066636002, 6578.375054369827, 6488.448481878904, 6523.0, 6536.0, 6658.3674550917585, 6697.665116744046, 6362.172917520693, 6521.217453905561, 6523.956167013255, 6527.608343499405, 6528.869422611616], "value": 6175.623325687302, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [813.4062134641712, 855.5209082913569, 918.1415376622854, 921.6497730862271, 930.9860352094718, 35525.934221973315, 37643.623563764355, 38764.41853372202, 38774.61225387745, 38793.806030969805, 32917.83541082291, 37744.622553774454, 38742.41886371707, 39004.219915601745, 39115.0, 7726.188750181221, 7782.182870798541, 7857.607119644023, 7871.8425631487335, 7875.763727088168, 7575.583342916138, 7643.3885289177015, 7734.8453030939345, 7801.726939557117, 7805.258500442477, 6430.678466076701, 7611.086669599628, 7714.8457030859345, 7800.10298815635, 7809.140994490602, 7568.432367572426, 7963.72126975556, 8385.329173666092, 8390.244877961013, 8402.957985210034, 8023.676093444575, 8208.0, 8213.753587392388, 8268.793280168044, 8272.669093236264, 35820.64179358199, 36196.819015905014, 36220.63779362199, 36307.4553881692, 36333.63666363355, 3422.110251334646, 3475.287566048979, 3493.406120959443, 3501.1072176594807, 3531.5762108546883, 28866.0, 29617.259568510744, 29900.55149172764, 29926.40147197044, 30357.544636830466, 2545.949081018388, 2839.531477306242, 3018.351054523266, 3025.0471101602925, 3045.7867949243564, 295649.0435095643, 304950.47524762456, 306579.0, 309556.0, 313074.0, 46787.76606117016, 47231.291530627066, 47723.761381192875, 47940.0, 48256.517434825204, 39446.80276598599, 49049.75475122673, 49255.75372123188, 50211.995760084596, 50494.495055049716, 14055.22696251716, 14287.57137285883, 14315.928420357832, 14337.64155896111, 14351.210683412304, 11425.371604561662, 11737.295762254293, 11741.062724650508, 11776.0, 11799.351035692947, 13134.34328283582, 13170.802437963439, 13242.271675057771, 13332.800007999887, 13367.532136375376, 5544.500994910397, 5626.521745651584, 5652.85867853307, 5686.715664216773, 5713.600167958876, 915.3318077803241, 922.9676961306322, 935.9391639543408, 979.363413781048, 980.1374790184734, 19450.610987780165, 20823.0, 23136.190233341753, 23659.52680946406, 23999.880000599886, 943.6932996775938, 964.8408012677829, 1028.7633844215873, 1045.2474218562622, 1070.2454769387564, 10132.594696212069, 10852.131829453625, 11773.352465614302, 12022.27866328023, 12095.092868034933, 277415.0, 337259.0, 342387.0, 349443.0, 350106.0, 50615.7469212659, 50746.0, 50883.745581271854, 50990.49009509932, 51097.48902510927, 1042.6246551241547, 1336.251699048536, 1419.858014198572, 1455.7088582283375, 1464.655805885616, 21914.013869376235, 22222.111115555195, 22347.66478502791, 22583.09667613277, 23213.071477140726, 26778.866105669345, 35390.46914296236, 35415.0, 35576.7548135814, 35652.82173589116, 293436.0656393409, 293992.53003734845, 295233.0, 295597.0, 300416.0, 28091.016814411396, 29224.83100675949, 29356.0, 32141.517877232327, 32458.188545286557, 35985.92042238737, 36635.63364366322, 39450.2109957805, 40059.1988160241, 40708.79645601753, 21644.567108658055, 24963.375915602257, 26577.867110664323, 27800.304992374542, 28630.0, 18039.819601803814, 21804.345869623936, 29349.706502934696, 29553.965611203505, 29873.70126298709, 37798.43302350522, 37860.0, 37886.62113378831, 37923.81038094902, 37968.0, 26799.86600066987, 27428.58857117105, 27709.168724938285, 29285.121446356643, 29291.41417171687, 19088.713669295248, 22448.663270050634, 22870.42823929415, 23960.041598335873, 23965.760342396352], "value": 16589.07749159214, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.4778256221714, 562.2381672833311, 565.5193085877005, 566.4929887750466, 576.6684156609949, 22427.77572224277, 22927.656085158716, 23031.654525182093, 23210.0, 23222.883885580548, 21719.782802171972, 23564.410889727766, 24407.633885491687, 25619.975200991983, 25935.481290374228, 6756.054152418666, 6909.378155965962, 6915.861682766367, 6923.792286231421, 6924.723011079551, 5624.437556244384, 6812.14848143982, 6875.449964002868, 6881.243063263037, 6904.0, 6149.569530132892, 6390.71241794118, 6546.509011824109, 6820.7805120556795, 6832.795016149498, 6549.508786840982, 6553.508486863504, 6572.868542629169, 6671.432928201109, 6680.632565208912, 4757.26262429324, 5795.652260864362, 6155.476784473325, 6158.414950579732, 6165.445109940127, 30860.0, 31179.84410077935, 31404.685953140404, 31760.047198584078, 31812.84093579517, 2184.563087382514, 2186.7922547357975, 2224.89987950541, 2233.899474523634, 2254.222293308806, 19090.0, 19377.224911003545, 19378.0, 19627.411177664693, 19734.506637334038, 1773.5300145461463, 2130.8934553272275, 2227.9665805012937, 2264.9207277745363, 2273.931782046525, 154484.0, 193908.0609193904, 194021.02989485103, 199132.0, 200039.0, 15640.530784076496, 15757.290921908541, 16091.597710057342, 16161.919190404207, 16359.918200408922, 22318.442038949157, 22632.755198463914, 22982.425439363815, 23017.654735178985, 23020.4244893879, 7926.524408535506, 9895.158911492605, 9985.800283994426, 10072.798544029078, 10202.897971020344, 8824.5587720615, 8975.28197744192, 9115.772105697411, 9126.0, 9167.083291670913, 7899.5260284381975, 9419.0, 9551.617935282511, 9702.320837541305, 9782.608695652094, 2395.4490467192645, 2555.9744402556107, 2702.513547561458, 2759.530879750447, 2920.707929207062, 904.8326059678981, 935.4153653966271, 941.6280569175275, 962.3215632978734, 1004.2819384140374, 14094.718105638034, 19774.90112549428, 21440.678389824167, 22064.88967555184, 22107.447313817283, 953.6376177052675, 983.4689267795453, 985.7338518599938, 1016.7915577306783, 1019.5768755966191, 10584.364938103738, 11147.498362573704, 11148.554057837759, 11179.552817887194, 11525.0, 538152.3092384591, 565262.0, 571009.0, 593733.0, 600467.0, 154061.0, 154630.0, 157041.42958570496, 157532.21233893986, 158246.0, 1127.5715228213226, 1152.1013609384693, 1167.0896700573567, 1169.368540987874, 1177.5937301630925, 18332.450026499228, 18901.716474252622, 19638.509037274183, 19798.802011979697, 19888.70723402974, 64891.35108648853, 66031.33968660251, 68431.65784171048, 68641.0, 68719.96920046103, 85502.57248713716, 86470.0, 87225.0, 89921.55039225024, 91033.08966910247, 9700.805983880424, 12024.699382515157, 12058.276503409823, 12217.51129954792, 12268.693282667648, 13776.96672749568, 17159.828401715822, 17303.91348043252, 17600.94394336344, 17743.201555929754, 11769.882301177222, 11798.056155507367, 11804.17370784037, 11858.229215101335, 11955.923966843025, 9824.607015719292, 11713.355765432983, 11851.0, 12405.44175512086, 12921.483140674643, 11401.714957126138, 14329.068610540282, 14465.855341446451, 14502.492412765503, 14512.927435363177, 11881.524739010689, 12098.274103553815, 12139.332336721565, 12153.149279550347, 12160.513579456723, 11133.053690436403, 13507.864921351054, 13622.795658064939, 13831.893448524295, 13876.306184690928], "value": 11629.802538781649, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1313.6863136863137, 1336.4253371050445, 1384.6953670192556, 1441.0633088492473, 1447.6163816588605, 14723.116613003227, 15327.233638318081, 15349.76975345368, 15383.230838458088, 15405.691886162298, 9875.308728389016, 12931.094823362368, 13018.869811301884, 14617.634559136, 15045.623859403493, 2761.585762135686, 2813.535766598509, 2856.057501024657, 2877.0649538899943, 2885.552739325408, 2781.9304517387127, 2832.7167283271715, 2838.801283910127, 2859.8999035033776, 2884.5096333623333, 2747.4230411613585, 2753.7796976241852, 2823.68939416664, 2851.2586727450803, 2853.529167687329, 2608.1393140263763, 2669.532831754445, 2674.5720684690455, 2791.5812628105746, 2801.4677211329986, 2206.470447092702, 2852.614896988901, 2865.8280503169876, 2922.7954043216982, 2930.3113768264443, 15004.099754014795, 15160.545183644397, 15185.924070379688, 15197.936144469892, 15209.61975950599, 6957.5129740918155, 8206.753797386087, 8469.36479764014, 8475.576221188916, 8502.702405415781, 4046.291898917678, 4921.4094308682825, 5227.163653815372, 5270.077736396153, 5282.683039017633, 1393.4147657983672, 1446.03115912339, 1466.0397439677029, 1479.7558402863515, 1490.7117574523054, 22912.54174916509, 23308.65037024446, 23424.64863027056, 23425.180118695935, 23605.409864753346, 6987.790366289019, 7031.964840175767, 7145.249748776408, 7251.746188883364, 7320.267973202637, 15538.533843984698, 15588.376464941275, 15955.563999240125, 15981.121038343003, 16000.839991600167, 7955.681772729026, 8118.10700822909, 8119.066307374609, 8127.634256458474, 8134.918650813534, 5400.540954018874, 7138.500304978602, 7169.175544812309, 7343.228960959129, 7487.7004919803685, 5296.549793267538, 5421.701806400686, 5518.862028449321, 5522.0336441122645, 5534.806281780118, 1336.8930485561327, 1596.6966276407445, 1608.8471595198437, 1616.9974615738317, 1628.3730763656079, 792.1563534835382, 803.1687203744096, 806.1173015547965, 821.2772759971187, 827.611022819274, 7290.9635451822405, 7535.660895259617, 7641.579713115721, 7692.499987500794, 8058.516489010678, 682.5154140560159, 692.2696555134281, 700.1108592088019, 704.3625518905399, 712.8396110875011, 3788.0, 4171.290880550314, 4731.810727570927, 5003.499650034967, 5180.32655754757, 14091.929540352232, 20135.993200339923, 20464.488387790127, 20484.795152048588, 20555.486112847, 17626.85425447342, 18141.546461338574, 19900.90049549743, 20235.0, 20463.97680116018, 9665.420074795535, 10031.69904902854, 10179.236557258087, 10182.23633227511, 10186.541605627617, 7395.334419902019, 8138.511689298661, 8267.83464330722, 8495.575221239036, 8697.173768491983, 13689.520866769615, 17851.821481785017, 18296.90851545787, 18506.0, 18534.907325463286, 17565.297388104333, 18008.459746207634, 18815.153318100445, 19030.33393831209, 19081.90459047696, 11461.312321260752, 11826.113041521749, 11882.28706277626, 12033.578824741442, 12142.878571214174, 11486.827697584376, 14084.647883802987, 14183.149011058957, 14211.431542738175, 14220.933429993, 7988.880166797619, 10126.544305506417, 10248.436336001592, 10356.948215259177, 10845.945770271099, 9970.351927124713, 10126.696199114038, 10133.493325333851, 10300.0, 10451.216158788273, 9211.217046551119, 9255.722328330161, 9309.162175404246, 9443.811123777348, 9464.95885452586, 6026.5383348143, 6467.482601391972, 6551.868962620816, 6841.486888483483, 7120.501564890408, 6803.659817009228, 6936.826579335355, 7061.082059332254, 7161.9641901790155, 7171.282871712787], "value": 6331.020011340465, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.8309383981007, 462.81057681757875, 483.258198665049, 496.6126421391315, 500.07486150621355, 4564.657650676196, 6026.246719160105, 6182.258129024518, 6220.117207991721, 6306.621602703841, 5822.7088645567765, 5897.616654917428, 6020.45815876571, 6076.574639775217, 6100.603460775046, 3622.492851000862, 4538.341940418637, 4601.217792975185, 4604.536572933482, 4614.146382919154, 3257.5928008998876, 4637.814487420499, 4655.790489427984, 4660.786126589876, 4666.883327916796, 4534.546545345455, 4588.655850811186, 4628.44458664959, 4634.2353511670535, 4636.814527418899, 3686.391745362012, 3976.542697589771, 4255.319148936186, 4596.54034596541, 4604.0, 3718.1819999600316, 4042.292598795199, 4048.736832105904, 4053.9189216215805, 4080.5511393746665, 5800.738966746507, 5887.293524777054, 5898.734556944948, 5901.911471327933, 5911.0, 4304.892377690551, 4856.761525810895, 4866.294387313855, 5535.750891209906, 5561.582881283879, 6407.359264073602, 6527.0, 6598.7030583623855, 6606.042123892009, 6621.437177839889, 4567.634589232854, 4658.836940707059, 4665.996810685719, 4668.836590719343, 4674.228752255874, 6002.378168810035, 6819.488538359593, 6896.586204827727, 6956.930430695679, 7018.263082376328, 3645.434957581579, 4693.545001049685, 4826.95173048272, 4830.758462076882, 4853.907870729057, 4585.656075794329, 4827.879303017453, 4865.172920603506, 4937.259411088366, 4939.604831613462, 5457.890842183134, 5461.1262198048125, 5549.6115271931385, 5557.722113894289, 5570.41510641385, 5637.830865074055, 6389.9680501597195, 6478.514111441661, 6541.771038013647, 6544.541882068304, 1651.3229575873834, 1657.4364715996378, 1661.7008938390964, 1686.1316422042657, 1692.213120898788, 1053.4311471805293, 1141.1840534018218, 1146.9311841289382, 1147.5811328864904, 1151.9884801151882, 4811.759412029454, 5950.732217050243, 6001.669908155093, 6015.30823955242, 6050.667213303311, 5815.680137592389, 6152.815415537541, 6522.412982831568, 6689.565178263398, 6703.240699103593, 5496.31296087989, 5912.556558258148, 5963.761449542057, 6011.609245399035, 6020.759169633166, 3067.8188897274263, 3107.5960125184033, 3427.8628854846024, 3492.0, 3513.631068737746, 5599.944000559943, 6220.440160385587, 6362.236531616143, 6791.185057793096, 6855.3845539055055, 6885.621290828953, 6921.653917304115, 6991.23096459389, 7022.367986881103, 7026.26224246457, 6775.424088952395, 6836.589804611739, 6936.306369363023, 6945.340192681687, 6959.164900212007, 5298.894022119459, 6220.471259942775, 6620.66896655175, 6643.202815662152, 6769.1876974763345, 5780.710964451845, 6359.534053940521, 6473.514486413444, 6625.4699624029, 6676.365745254193, 6300.991841305369, 6367.681615919279, 6581.0, 6859.691313890787, 6944.895826562504, 4091.6112969267874, 4231.217224813419, 4493.505714371482, 4603.654725895638, 4771.78319528518, 6683.0, 6689.09697190873, 6781.42357899584, 6814.250432452525, 6950.6177160256675, 5111.974440127776, 6246.406591373812, 6409.262934762562, 6463.57986730861, 6503.25212600557, 5682.6306290091015, 5827.24245848037, 6299.842503937438, 6557.868842623026, 6597.835054123685, 5048.823291184791, 5287.7620507078045, 6217.222847144107, 6241.625502469867, 6467.450266727381, 6411.166548348685, 6419.876521608607, 6569.605823650596, 6599.0, 6613.801585952237, 5511.4213007633625, 5512.6968016759465, 6240.28236752761, 6376.936230637634, 6416.2300523935555], "value": 4801.895451409771, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.6894907562437, 837.2130197614246, 866.9479831210132, 875.277895736018, 879.1560102301788, 16947.406840760577, 17420.216090275935, 17420.65158696825, 17431.21559529821, 19031.904840475814, 18357.17392717331, 18696.626067478675, 18835.340763073295, 18940.052997350147, 19039.52401189971, 936.7189843047092, 1038.5638032026568, 1040.1990467340142, 1040.583766493405, 1061.1086687182765, 971.3249291742242, 1000.4697510319543, 1015.228426395937, 1021.9131373833233, 1032.917366610673, 1052.4842827014745, 1055.208593554834, 1079.3631757263213, 1086.3373342261214, 1092.6066616018227, 965.2760429677742, 1050.296301478004, 1077.391273930231, 1080.7460246841986, 1098.774751176007, 774.3959711425098, 791.7149826062613, 792.2196636313226, 798.8641930871765, 802.4022103532906, 17102.31590736369, 17411.0, 17660.02869842158, 17777.46667599974, 17801.287948482044, 16824.663506729918, 16948.915255423643, 17829.10854457272, 17944.371946981923, 17945.641087178316, 14047.719045619031, 14657.0, 14698.971072024962, 14712.632184195374, 14804.629884252872, 2741.191348552182, 3609.2781443711365, 3621.257642183346, 3672.265546890606, 3696.2792255510185, 146840.0, 152679.23660381738, 154451.0, 160289.0, 161306.19346903308, 11107.722306942229, 13653.590392288248, 13780.310984450738, 13918.652033699238, 13982.161070335813, 20890.686639700416, 26939.461210776066, 27258.72741272562, 27491.31271718223, 27712.584311235347, 7836.0, 7952.562609056557, 8119.3910456715985, 8148.144444833326, 8194.09864914859, 3443.776154549946, 3920.725549211528, 3946.8026598670526, 4030.979845100756, 4056.6146216109423, 4491.977540112344, 4583.312503124558, 5260.579153667697, 5564.332280126329, 5682.664573825148, 1107.3189988157226, 1123.3260043973714, 1127.7575321305958, 1139.407508095802, 1141.303804679145, 560.9986174678261, 598.8023952095798, 612.1919066831829, 636.8407898025494, 639.7377075399064, 9327.673531426368, 10742.841181722531, 11140.777184456265, 11477.713057173638, 12544.372781360895, 574.4800955135609, 627.8555932135635, 639.7153266796231, 656.9146011018537, 658.7002913674266, 5078.339815823919, 5245.1345527988215, 5347.41178470368, 5503.257060296809, 5734.971325143348, 127220.36389817992, 133520.0, 136156.0, 136774.3161284207, 140253.0, 113294.43352783183, 115039.0, 116183.41908290405, 117739.0, 121030.3948480252, 95661.52169239109, 100998.0, 103632.0, 105600.88798223993, 106141.46929265458, 12136.089793265364, 12210.572629958264, 12291.44688489038, 12317.322547259986, 12802.807957880825, 50590.49409505858, 51875.74062129665, 52161.47838521566, 52173.43479695615, 52265.21602175894, 68410.65794670995, 69312.30687693058, 71111.28888711253, 72005.6399717998, 72220.63889680729, 16977.575560611083, 17135.914320428317, 17196.398126065527, 17247.65504689874, 17284.481465556055, 17338.566535836704, 17534.298628055247, 17727.0, 18150.364737234133, 18562.628747425246, 15237.085774853544, 15571.688566228839, 17139.0, 17141.48575542736, 17159.485215443055, 16821.2430440628, 17140.914295428443, 17213.0, 17316.82683173152, 17424.82575174232, 16341.836581634032, 16802.411915582896, 16879.662406752042, 16989.66020679604, 17381.82618173853, 11771.470283837076, 11811.409429528661, 12381.0, 12811.679708007374, 12947.5468358607, 12557.183783054072, 12615.936920315708, 12677.366131693563, 12924.612261632166, 13185.60443186706], "value": 8900.80003274908, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 112.0, 119.0, 161.0, 93.0, 131.0, 164.0, 165.0, 121.0, 149.0], "value": 132.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 118.0, 146.0, 148.0, 112.0, 130.0, 111.0, 150.0, 152.0, 115.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 204.0, 214.0, 229.0, 233.0, 201.0, 203.0, 204.0, 205.0, 197.0], "value": 209.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 109.0, 123.0, 137.0, 47.0, 50.0, 67.0, 64.0, 121.0, 47.0], "value": 84.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 112.0, 69.0, 71.0, 85.0, 114.0, 71.0, 75.0, 112.0], "value": 84.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 91.0, 91.0, 93.0, 94.0, 93.0, 126.0, 136.0, 93.0, 107.0], "value": 101.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 130.0, 150.0, 99.0, 97.0, 100.0, 98.0, 98.0, 96.0], "value": 106.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 93.0, 91.0, 89.0, 92.0, 93.0, 92.0, 91.0, 90.0], "value": 91.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 51.0, 56.0, 53.0, 56.0, 53.0, 55.0, 53.0, 54.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 56.0, 55.0, 59.0, 61.0, 61.0, 57.0, 59.0, 57.0, 56.0], "value": 58.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 82.0, 82.0, 82.0, 84.0, 82.0, 81.0, 86.0, 81.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 140.0, 139.0, 190.0, 136.0, 172.0, 168.0, 170.0, 147.0, 165.0], "value": 156.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 187.0, 201.0, 227.0, 185.0, 202.0, 275.0, 233.0, 186.0, 187.0], "value": 207.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 66.0, 68.0, 67.0, 69.0, 65.0, 62.0, 65.0], "value": 66.3, "unit": "score"}]}]} 12:29:06 INFO - Return code: 0 12:29:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:29:06 INFO - # TBPL SUCCESS # 12:29:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:29:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:29:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:29:06 INFO - 2017-06-21 12:29:06.300 screenresolution[2494:33111] starting screenresolution argv=screenresolution get 12:29:06 INFO - 2017-06-21 12:29:06.302 screenresolution[2494:33111] Display 0: 1600x1200x32@60 12:29:06 INFO - 2017-06-21 12:29:06.308 screenresolution[2495:33114] starting screenresolution argv=screenresolution list 12:29:06 INFO - Available Modes on Display 0 12:29:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:29:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:29:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:29:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:29:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:29:06 INFO - Intel Iris: 12:29:06 INFO - Chipset Model: Intel Iris 12:29:06 INFO - Type: GPU 12:29:06 INFO - Bus: Built-In 12:29:06 INFO - VRAM (Dynamic, Max): 1536 MB 12:29:06 INFO - Vendor: Intel (0x8086) 12:29:06 INFO - Device ID: 0x0a2e 12:29:06 INFO - Revision ID: 0x0009 12:29:06 INFO - Displays: 12:29:06 INFO - Display: 12:29:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:29:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:29:06 INFO - Display Serial Number: 1600x1200 60 12:29:06 INFO - Main Display: Yes 12:29:06 INFO - Mirror: Off 12:29:06 INFO - Online: Yes 12:29:06 INFO - Rotation: Supported 12:29:06 INFO - Return code: 0 12:29:06 INFO - Running post-action listener: _resource_record_post_action 12:29:06 INFO - [mozharness: 2017-06-21 19:29:06.545778Z] Finished run-tests step (success) 12:29:06 INFO - Running post-run listener: _resource_record_post_run 12:29:06 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 204800; Write bytes: 573369344; Read time: 2; Write time: 1973 12:29:06 INFO - TinderboxPrint: CPU usage
54.3% 12:29:06 INFO - TinderboxPrint: I/O read bytes / time
204,800 / 2 12:29:06 INFO - TinderboxPrint: I/O write bytes / time
573,369,344 / 1,973 12:29:06 INFO - TinderboxPrint: CPU idle
2,202.2 (45.7%) 12:29:06 INFO - TinderboxPrint: CPU system
994.4 (20.7%) 12:29:06 INFO - TinderboxPrint: CPU user
1,617.1 (33.6%) 12:29:06 INFO - TinderboxPrint: Swap in / out
253,665,280 / 0 12:29:06 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 241054208; Write bytes: 239724544; Read time: 17631; Write time: 583 12:29:06 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:29:06 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 204800; Write bytes: 314315776; Read time: 2; Write time: 1343 12:29:07 INFO - Running post-run listener: _upload_blobber_files 12:29:07 INFO - Blob upload gear active. 12:29:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:29:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:29:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:29:07 INFO - (blobuploader) - INFO - Open directory for files ... 12:29:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:29:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:29:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:08 INFO - (blobuploader) - INFO - Done attempting. 12:29:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:29:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:29:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:10 INFO - (blobuploader) - INFO - Done attempting. 12:29:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:29:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:29:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:11 INFO - (blobuploader) - INFO - Done attempting. 12:29:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:29:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:29:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:14 INFO - (blobuploader) - INFO - Done attempting. 12:29:14 INFO - (blobuploader) - INFO - Iteration through files over. 12:29:14 INFO - Return code: 0 12:29:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:29:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:29:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fcfd5d8852f56778089655446d7fc190fd1ff8da9c5d7bf20325415c10fdda5526100bad50b05085b03c2942b2e66f004bc2718b3b5c2060d00df77aa28145fd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fef9addc6ab0b24b2c77069dbf64e7f8b7a165e0556c76434577edca29efd7fe928124363058f66c60937884428ef8535d9314af1db85ea5e4ce5e30d7109651", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/93c89864b1af46e51c6bce58674399a0683d65d2638c2bc1077642c4ff7da9ffb109c881464f2fcb1dd960b01024dd2e5f861ce98ef420659d16014296bec949", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/cb990b3cf9ba2ff9b3eb6cd7e0b522c0becc670c806c4c25df79d69caa17aaf03a9d44b78f4c6fe25a59f14696277d8718cab2829eabe557d4e5d9135be7f58c"} 12:29:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:29:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:29:14 INFO - Contents: 12:29:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fcfd5d8852f56778089655446d7fc190fd1ff8da9c5d7bf20325415c10fdda5526100bad50b05085b03c2942b2e66f004bc2718b3b5c2060d00df77aa28145fd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fef9addc6ab0b24b2c77069dbf64e7f8b7a165e0556c76434577edca29efd7fe928124363058f66c60937884428ef8535d9314af1db85ea5e4ce5e30d7109651", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/93c89864b1af46e51c6bce58674399a0683d65d2638c2bc1077642c4ff7da9ffb109c881464f2fcb1dd960b01024dd2e5f861ce98ef420659d16014296bec949", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/cb990b3cf9ba2ff9b3eb6cd7e0b522c0becc670c806c4c25df79d69caa17aaf03a9d44b78f4c6fe25a59f14696277d8718cab2829eabe557d4e5d9135be7f58c"} 12:29:14 INFO - Running post-run listener: copy_logs_to_upload_dir 12:29:14 INFO - Copying logs to upload dir... 12:29:14 INFO - mkdir: /builds/slave/test/build/upload/logs 12:29:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.457223 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-06-21 12:29:15.051035) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-21 12:29:15.054535) ========= 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.ZO0xM4brs3/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.suUwQngqSS/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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/fcfd5d8852f56778089655446d7fc190fd1ff8da9c5d7bf20325415c10fdda5526100bad50b05085b03c2942b2e66f004bc2718b3b5c2060d00df77aa28145fd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fef9addc6ab0b24b2c77069dbf64e7f8b7a165e0556c76434577edca29efd7fe928124363058f66c60937884428ef8535d9314af1db85ea5e4ce5e30d7109651", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/93c89864b1af46e51c6bce58674399a0683d65d2638c2bc1077642c4ff7da9ffb109c881464f2fcb1dd960b01024dd2e5f861ce98ef420659d16014296bec949", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/cb990b3cf9ba2ff9b3eb6cd7e0b522c0becc670c806c4c25df79d69caa17aaf03a9d44b78f4c6fe25a59f14696277d8718cab2829eabe557d4e5d9135be7f58c"} 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.011187 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/fcfd5d8852f56778089655446d7fc190fd1ff8da9c5d7bf20325415c10fdda5526100bad50b05085b03c2942b2e66f004bc2718b3b5c2060d00df77aa28145fd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fef9addc6ab0b24b2c77069dbf64e7f8b7a165e0556c76434577edca29efd7fe928124363058f66c60937884428ef8535d9314af1db85ea5e4ce5e30d7109651", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/93c89864b1af46e51c6bce58674399a0683d65d2638c2bc1077642c4ff7da9ffb109c881464f2fcb1dd960b01024dd2e5f861ce98ef420659d16014296bec949", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/cb990b3cf9ba2ff9b3eb6cd7e0b522c0becc670c806c4c25df79d69caa17aaf03a9d44b78f4c6fe25a59f14696277d8718cab2829eabe557d4e5d9135be7f58c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-21 12:29:15.090355) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:29:15.090974) ========= 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.ZO0xM4brs3/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.suUwQngqSS/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.004807 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-21 12:29:15.140207) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-21 12:29:15.140523) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-21 12:29:15.140806) ========= ========= Total master_lag: 0.52 =========