1145906 - Mochitest failure summaries frequently showing "ValueError: invalid literal for int() with base 10: 'ps:'" twice

Status: RESOLVED DUPLICATE of bug 1145364

(Testing :: Mochitest, defect)

Description

[Wes Kocher (:KWierso) (Not reading bugmail; email directly if needed)]

I've been seeing this for a few days now. When I look at the log for various mochitest failures, I'm seeing the following right at the beginning of the run-tests step:

15:07:54 INFO - #### Running mochitest suites
15:07:54 INFO - grabbing minidump binary from tooltool
15:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://tooltool.pvt.build.mozilla.org', 'tooltool.pvt.build.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:07:54 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'http://runtime-binaries.pvt.build.mozilla.org/tooltool/', 'fetch', '-m', '/builds/slave/talos-slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebdc1d0>, '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 0x10ebca030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec751c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/talos-slave/test/build', 'privileged': False}, attempt #1
15:07:54 INFO - Running command: ['/tools/tooltool.py', '--url', 'http://runtime-binaries.pvt.build.mozilla.org/tooltool/', 'fetch', '-m', '/builds/slave/talos-slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/talos-slave/test/build
15:07:54 INFO - Copy/paste: /tools/tooltool.py --url http://runtime-binaries.pvt.build.mozilla.org/tooltool/ fetch -m /builds/slave/talos-slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache
15:07:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
15:07:54 INFO - Return code: 0
15:07:54 INFO - Chmoding /builds/slave/talos-slave/test/build/macosx64-minidump_stackwalk to 0755
15:07:54 INFO - mkdir: /builds/slave/talos-slave/test/build/blobber_upload_dir
15:07:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/talos-slave/test/build/blobber_upload_dir
15:07:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/talos-slave/test/build/macosx64-minidump_stackwalk
15:07:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/talos-slave/test/build/blobber_upload_dir
15:07:54 INFO - Running command: ['/builds/slave/talos-slave/test/build/venv/bin/python', '-u', '/builds/slave/talos-slave/test/build/tests/mochitest/runtests.py', '--total-chunks', '5', '--this-chunk', '1', '--appname=/builds/slave/talos-slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--utility-path=tests/bin', '--extra-profile-file=tests/bin/plugins', '--symbols-path=https://ftp-ssl.mozilla.org/pub/mozilla.org/firefox/tinderbox-builds/fx-team-macosx64/1426881428/firefox-39.0a1.en-US.mac.crashreporter-symbols.zip', '--certificate-path=tests/certs', '--autorun', '--close-when-done', '--console-level=INFO', '--quiet', '--log-raw=/builds/slave/talos-slave/test/build/blobber_upload_dir/plain-chunked_raw.log', '--chunk-by-dir=4'] in /builds/slave/talos-slave/test/build
15:07:54 INFO - Copy/paste: /builds/slave/talos-slave/test/build/venv/bin/python -u /builds/slave/talos-slave/test/build/tests/mochitest/runtests.py --total-chunks 5 --this-chunk 1 --appname=/builds/slave/talos-slave/test/build/application/Nightly.app/Contents/MacOS/firefox --utility-path=tests/bin --extra-profile-file=tests/bin/plugins --symbols-path=https://ftp-ssl.mozilla.org/pub/mozilla.org/firefox/tinderbox-builds/fx-team-macosx64/1426881428/firefox-39.0a1.en-US.mac.crashreporter-symbols.zip --certificate-path=tests/certs --autorun --close-when-done --console-level=INFO --quiet --log-raw=/builds/slave/talos-slave/test/build/blobber_upload_dir/plain-chunked_raw.log --chunk-by-dir=4
15:07:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PpUHm78OeF/Render',
15:07:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
15:07:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
15:07:54 INFO - 'HOME': '/Users/cltbld',
15:07:54 INFO - 'LOGNAME': 'cltbld',
15:07:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/talos-slave/test/build/blobber_upload_dir',
15:07:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/talos-slave/test/build/macosx64-minidump_stackwalk',
15:07:54 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1',
15:07:54 INFO - 'MOZ_NO_REMOTE': '1',
15:07:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/talos-slave/test/build/blobber_upload_dir',
15:07:54 INFO - 'NO_EM_RESTART': '1',
15:07:54 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1',
15:07:54 INFO - 'PATH': '/builds/slave/talos-slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
15:07:54 INFO - 'PROPERTIES_FILE': '/builds/slave/talos-slave/test/buildprops.json',
15:07:54 INFO - 'PWD': '/builds/slave/talos-slave/test',
15:07:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
15:07:54 INFO - 'SHELL': '/bin/bash',
15:07:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SKlipqwFOy/Listeners',
15:07:54 INFO - 'TMPDIR': '/var/folders/dr/ktlprtp52g3_lm39fsfxwnhh00000w/T/',
15:07:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
15:07:54 INFO - 'USER': 'cltbld',
15:07:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
15:07:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
15:07:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:07:54 INFO - 'XPC_FLAGS': '0x0',
15:07:54 INFO - 'XPC_SERVICE_NAME': '0',
15:07:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'}
15:07:54 INFO - Calling ['/builds/slave/talos-slave/test/build/venv/bin/python', '-u', '/builds/slave/talos-slave/test/build/tests/mochitest/runtests.py', '--total-chunks', '5', '--this-chunk', '1', '--appname=/builds/slave/talos-slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--utility-path=tests/bin', '--extra-profile-file=tests/bin/plugins', '--symbols-path=https://ftp-ssl.mozilla.org/pub/mozilla.org/firefox/tinderbox-builds/fx-team-macosx64/1426881428/firefox-39.0a1.en-US.mac.crashreporter-symbols.zip', '--certificate-path=tests/certs', '--autorun', '--close-when-done', '--console-level=INFO', '--quiet', '--log-raw=/builds/slave/talos-slave/test/build/blobber_upload_dir/plain-chunked_raw.log', '--chunk-by-dir=4'] with output_timeout 1000
15:07:54 INFO - /builds/slave/talos-slave/test/build/venv/lib/python2.7/site-packages/mozrunner/utils.py:20: UserWarning: Module moznetwork was already imported from /builds/slave/talos-slave/test/build/tests/mochitest/moznetwork.py, but /builds/slave/talos-slave/test/build/venv/lib/python2.7/site-packages is being added to sys.path
15:07:54 INFO - import pkg_resources
15:07:54 INFO - Checking for orphan ssltunnel processes...
15:07:54 INFO - Exception in thread ProcessReader:
15:07:54 INFO - Traceback (most recent call last):
15:07:54 INFO - File "/tools/python27/lib/python2.7/threading.py", line 551, in __bootstrap_inner
15:07:54 INFO - self.run()
15:07:54 INFO - File "/tools/python27/lib/python2.7/threading.py", line 504, in run
15:07:54 INFO - self.__target(*self.__args, **self.__kwargs)
15:07:54 INFO - File "/builds/slave/talos-slave/test/build/venv/lib/python2.7/site-packages/mozprocess/processhandler.py", line 894, in _read
15:07:54 INFO - callback(line.rstrip())
15:07:54 INFO - File "/builds/slave/talos-slave/test/build/venv/lib/python2.7/site-packages/mozprocess/processhandler.py", line 814, in __call__
15:07:54 INFO - e(*args, **kwargs)
15:07:54 INFO - File "/builds/slave/talos-slave/test/build/tests/mochitest/runtests.py", line 2045, in _psKill
15:07:54 INFO - pid = int(parts[0])
15:07:54 INFO - ValueError: invalid literal for int() with base 10: 'ps:'
15:07:54 INFO - Checking for orphan xpcshell processes...
15:07:54 INFO - Exception in thread ProcessReader:
15:07:54 INFO - Traceback (most recent call last):
15:07:54 INFO - File "/tools/python27/lib/python2.7/threading.py", line 551, in __bootstrap_inner
15:07:54 INFO - self.run()
15:07:54 INFO - File "/tools/python27/lib/python2.7/threading.py", line 504, in run
15:07:54 INFO - self.__target(*self.__args, **self.__kwargs)
15:07:54 INFO - File "/builds/slave/talos-slave/test/build/venv/lib/python2.7/site-packages/mozprocess/processhandler.py", line 894, in _read
15:07:54 INFO - callback(line.rstrip())
15:07:54 INFO - File "/builds/slave/talos-slave/test/build/venv/lib/python2.7/site-packages/mozprocess/processhandler.py", line 814, in __call__
15:07:54 INFO - e(*args, **kwargs)
15:07:54 INFO - File "/builds/slave/talos-slave/test/build/tests/mochitest/runtests.py", line 2045, in _psKill
15:07:54 INFO - pid = int(parts[0])
15:07:54 INFO - ValueError: invalid literal for int() with base 10: 'ps:'
15:07:55 INFO - SUITE-START | Running 1352 tests
15:07:55 INFO - TEST-START | browser/base/content/test/general/test_contextmenu.html
15:07:55 INFO - TEST-SKIP | browser/base/content/test/general/test_contextmenu.html | took 0ms
15:07:55 INFO - TEST-START | docshell/test/test_bug413310.html
15:07:55 INFO - TEST-SKIP | docshell/test/test_bug413310.html | took 0ms
15:07:55 INFO - TEST-START | dom/audiochannel/tests/test_audioChannelChange.html
15:07:55 INFO - TEST-SKIP | dom/audiochannel/tests/test_audioChannelChange.html | took 1ms