Bug 1565332 Comment 37 Edit History

Note: The actual edited comment in the bug view page will always show the original commenter’s name and original timestamp.

:kinetik - I'm not sure who else I could reach out to regarding pulseaudio issues that I'm still running into; I cannot seem to have it reliably start and remain started in my test image, even after the fixes in bug 1572311 (setting `pulseaudio --exit-idle-time=-1`). I've kept that bug closed since this is not a GTest specific question.

GTest - https://treeherder.mozilla.org/#/jobs?repo=try&group_state=expanded&revision=fb9298e8a6a5811b82cdb60aa8448ec6088d597a
Other suites (mochitest in particular): https://treeherder.mozilla.org/#/jobs?repo=try&group_state=expanded&selectedJob=263525493&revision=3618c4ddabcba50c66b937a2be770354455a941b

Would you have any ideas or hints for me to try out? As noted in bug 1572311, changing the timer to `-1` seems to fix GTest but only about 50% of the time. If you know someone that might be better suited to help, let me know - this pulseaudio issue has been plaguing my efforts since the beginning as this bug thread shows.
:kinetik - I'm not sure who else I could reach out to regarding pulseaudio issues that I'm still running into; I cannot seem to have it reliably start and remain started in my test image, even after the fixes in bug 1572311 (setting `pulseaudio --exit-idle-time=-1`). I've kept that bug closed since this is not a GTest specific question.

GTest - https://treeherder.mozilla.org/#/jobs?repo=try&group_state=expanded&revision=fb9298e8a6a5811b82cdb60aa8448ec6088d597a
Other suites (mochitest in particular): https://treeherder.mozilla.org/#/jobs?repo=try&group_state=expanded&selectedJob=263525493&revision=3618c4ddabcba50c66b937a2be770354455a941b

When `test-linux.sh` initializes `pulseaudio`, the following is the log:
```
[task 2019-08-26T19:34:43.889Z] + pulseaudio --fail --daemonize --start -vvvv
[task 2019-08-26T19:34:43.912Z] D: [pulseaudio] conf-parser.c: Parsing configuration file '/etc/pulse/client.conf'
[task 2019-08-26T19:34:43.912Z] D: [pulseaudio] conf-parser.c: Parsing configuration file '/etc/pulse/client.conf.d/00-disable-autospawn.conf'
[task 2019-08-26T19:34:43.952Z] I: [pulseaudio] main.c: Daemon startup successful.
[task 2019-08-26T19:34:43.953Z] + pulseaudio --check
[task 2019-08-26T19:34:43.960Z] + '[' 0 -eq 0 ']'
[task 2019-08-26T19:34:43.960Z] + echo 'Pulseaudio successfully initialized'
[task 2019-08-26T19:34:43.960Z] Pulseaudio successfully initialized
[task 2019-08-26T19:34:43.960Z] + pactl load-module module-null-sink
[task 2019-08-26T19:34:43.969Z] 16
```
When the test is then run in the harness, despite my added code to check for `pulseaudio` and call the same initialization as in `test-linux.sh` if it is not running, the following is the output:

```
[task 2019-08-26T19:35:58.029Z] 19:35:58     INFO - Running manifest: accessible/tests/browser/browser.ini
[task 2019-08-26T19:35:58.306Z] 19:35:58     INFO -  Setting pipeline to PAUSED ...
[task 2019-08-26T19:35:58.306Z] 19:35:58     INFO -  libv4l2: error getting pixformat: Invalid argument
[task 2019-08-26T19:35:58.307Z] 19:35:58     INFO -  Pipeline is PREROLLING ...
[task 2019-08-26T19:35:58.307Z] 19:35:58     INFO -  Pipeline is PREROLLED ...
[task 2019-08-26T19:35:58.307Z] 19:35:58     INFO -  Setting pipeline to PLAYING ...
[task 2019-08-26T19:35:58.307Z] 19:35:58     INFO -  New clock: GstSystemClock
[task 2019-08-26T19:35:58.335Z] 19:35:58     INFO -  Got EOS from element "pipeline0".
[task 2019-08-26T19:35:58.335Z] 19:35:58     INFO -  Execution ended after 33401858 ns.
[task 2019-08-26T19:35:58.335Z] 19:35:58     INFO -  Setting pipeline to PAUSED ...
[task 2019-08-26T19:35:58.335Z] 19:35:58     INFO -  Setting pipeline to READY ...
[task 2019-08-26T19:35:58.335Z] 19:35:58     INFO -  Setting pipeline to NULL ...
[task 2019-08-26T19:35:58.336Z] 19:35:58     INFO -  Freeing pipeline ...
[task 2019-08-26T19:35:58.344Z] 19:35:58     INFO -  Connection failure: Connection refused
[task 2019-08-26T19:35:58.344Z] 19:35:58     INFO -  pa_context_connect() failed: Connection refused
[task 2019-08-26T19:35:58.351Z] 19:35:58     INFO -  D: [pulseaudio] conf-parser.c: Parsing configuration file '/etc/pulse/client.conf'
[task 2019-08-26T19:35:58.352Z] 19:35:58     INFO -  D: [pulseaudio] conf-parser.c: Parsing configuration file '/etc/pulse/client.conf.d/00-disable-autospawn.conf'
[task 2019-08-26T19:35:58.353Z] 19:35:58     INFO -  N: [pulseaudio] main.c: User-configured server at {689cfabc30776e6bfe2e7477e81eaa6d}unix:/tmp/pulse-qrpFpnpvYwVl/native, which appears to be local. Probing deeper.
[task 2019-08-26T19:35:58.354Z] 19:35:58     INFO -  I: [pulseaudio] main.c: Daemon startup successful.
[task 2019-08-26T19:35:58.360Z] 19:35:58     INFO -  Connection failure: Connection refused
[task 2019-08-26T19:35:58.360Z] 19:35:58     INFO -  pa_context_connect() failed: Connection refused
[task 2019-08-26T19:35:58.360Z] 19:35:58     INFO -  Traceback (most recent call last):
[task 2019-08-26T19:35:58.360Z] 19:35:58     INFO -    File "/builds/worker/workspace/build/tests/mochitest/runtests.py", line 3191, in <module>
[task 2019-08-26T19:35:58.361Z] 19:35:58     INFO -      sys.exit(cli())
[task 2019-08-26T19:35:58.361Z] 19:35:58     INFO -    File "/builds/worker/workspace/build/tests/mochitest/runtests.py", line 3187, in cli
[task 2019-08-26T19:35:58.361Z] 19:35:58     INFO -      return run_test_harness(parser, options)
[task 2019-08-26T19:35:58.361Z] 19:35:58     INFO -    File "/builds/worker/workspace/build/tests/mochitest/runtests.py", line 3172, in run_test_harness
[task 2019-08-26T19:35:58.361Z] 19:35:58     INFO -      result = runner.runTests(options)
[task 2019-08-26T19:35:58.361Z] 19:35:58     INFO -    File "/builds/worker/workspace/build/tests/mochitest/runtests.py", line 2675, in runTests
[task 2019-08-26T19:35:58.361Z] 19:35:58     INFO -      res = self.runMochitests(options, tests_in_manifest)
[task 2019-08-26T19:35:58.362Z] 19:35:58     INFO -    File "/builds/worker/workspace/build/tests/mochitest/runtests.py", line 2454, in runMochitests
[task 2019-08-26T19:35:58.362Z] 19:35:58     INFO -      result = self.doTests(options, testsToRun)
[task 2019-08-26T19:35:58.364Z] 19:35:58     INFO -    File "/builds/worker/workspace/build/tests/mochitest/runtests.py", line 2736, in doTests
[task 2019-08-26T19:35:58.364Z] 19:35:58     INFO -      devices = findTestMediaDevices(self.log)
[task 2019-08-26T19:35:58.365Z] 19:35:58     INFO -    File "/builds/worker/workspace/build/tests/mochitest/runtests.py", line 830, in findTestMediaDevices
[task 2019-08-26T19:35:58.366Z] 19:35:58     INFO -      'module-null-sink'
[task 2019-08-26T19:35:58.367Z] 19:35:58     INFO -    File "/usr/lib/python2.7/subprocess.py", line 190, in check_call
[task 2019-08-26T19:35:58.368Z] 19:35:58     INFO -      raise CalledProcessError(retcode, cmd)
[task 2019-08-26T19:35:58.368Z] 19:35:58     INFO -  subprocess.CalledProcessError: Command '['/usr/bin/pactl', 'load-module', 'module-null-sink']' returned non-zero exit status 1
[task 2019-08-26T19:35:58.389Z] 19:35:58    ERROR - Return code: 1
```

Would you have any ideas or hints for me to try out? As noted in bug 1572311, changing the timer to `-1` seems to fix GTest but only about 50% of the time. If you know someone that might be better suited to help, let me know - this pulseaudio issue has been plaguing my efforts since the beginning as this bug thread shows.

Back to Bug 1565332 Comment 37