https://luci-milo.appspot.com/buildbot/chromeos/edgar-paladin/348

This edgar build failed audio_Microphone, but following CQ builds (with roughly identical CL sets) passed. I believe this was test flake.

Error seen:

08/05 19:07:50.438 INFO |         cmd_utils:0202| [0010] pid is 8354
08/05 19:07:50.444 INFO |         cmd_utils:0196| [0011] Running: /usr/bin/arecord -l
08/05 19:07:50.449 INFO |         cmd_utils:0202| [0011] pid is 8355
08/05 19:07:50.454 INFO |         cmd_utils:0196| [0012] Running: /usr/bin/arecord -d 3 -c 1 -f S16_LE -r 44100 -D plughw:1,0 /tmp/tmpUqdx5l
08/05 19:07:50.461 INFO |         cmd_utils:0202| [0012] pid is 8356
08/05 19:07:50.466 ERROR|         cmd_utils:0091| [0012] arecord: main:788: audio open error: Device or resource busy
08/05 19:07:50.466 ERROR|         cmd_utils:0155| Command failed(8356, 1): /usr/bin/arecord -d 3 -c 1 -f S16_LE -r 44100 -D plughw:1,0 /tmp/tmpUqdx5l
08/05 19:07:50.476 ERROR|         cmd_utils:0091| [0012] 
08/05 19:07:50.482 DEBUG|              test:0389| Test failed due to Command failed(8356, 1): /usr/bin/arecord -d 3 -c 1 -f S16_LE -r 44100 -D plughw:1,0 /tmp/tmpUqdx5l. Exception log follows the after_iteration_hooks.
08/05 19:07:50.482 DEBUG|              test:0392| starting after_iteration_hooks
08/05 19:07:50.485 DEBUG|             utils:0212| Running 'mkdir -p /usr/local/autotest/results/default/audio_Microphone/sysinfo/iteration.1/var/spool'
08/05 19:07:50.501 DEBUG|             utils:0212| Running 'rsync --no-perms --chmod=ugo+r -a --safe-links --exclude=/crash/**autoserv* --exclude=/crash/*.core /var/spool/crash /usr/local/autotest/results/default/audio_Microphone/sysinfo/iteration.1/var/spool'
08/05 19:07:50.517 DEBUG|             utils:0212| Running 'rm -rf /var/spool/crash/*'
08/05 19:07:50.532 DEBUG|             utils:0212| Running 'logger "autotest finished iteration /usr/local/autotest/results/default/audio_Microphone/sysinfo/iteration.1"'
08/05 19:07:50.543 DEBUG|              test:0395| after_iteration_hooks completed
08/05 19:07:50.546 WARNI|              test:0612| The test failed with the following exception
Traceback (most recent call last):
  File "/usr/local/autotest/common_lib/test.py", line 606, in _exec
    _call_test_function(self.execute, *p_args, **p_dargs)
  File "/usr/local/autotest/common_lib/test.py", line 812, in _call_test_function
    raise error.UnhandledTestFail(e)
UnhandledTestFail: Unhandled RuntimeError: Command failed(8356, 1): /usr/bin/arecord -d 3 -c 1 -f S16_LE -r 44100 -D plughw:1,0 /tmp/tmpUqdx5l
Traceback (most recent call last):
  File "/usr/local/autotest/common_lib/test.py", line 806, in _call_test_function
    return func(*args, **dargs)
  File "/usr/local/autotest/common_lib/test.py", line 470, in execute
    dargs)
  File "/usr/local/autotest/common_lib/test.py", line 347, in _call_run_once_with_retry
    postprocess_profiled_run, args, dargs)
  File "/usr/local/autotest/common_lib/test.py", line 380, in _call_run_once
    self.run_once(*args, **dargs)
  File "/usr/local/autotest/tests/audio_Microphone/audio_Microphone.py", line 56, in run_once
    self.verify_alsa_capture(c, 44100)
  File "/usr/local/autotest/tests/audio_Microphone/audio_Microphone.py", line 31, in verify_alsa_capture
    bits=bits, rate=rate)
  File "/usr/local/autotest/cros/audio/alsa_utils.py", line 276, in record
    cmd_utils.execute(record_cmd(*args, **kwargs))
  File "/usr/local/autotest/cros/audio/cmd_utils.py", line 173, in execute
    wait_and_check_returncode(ps)
  File "/usr/local/autotest/cros/audio/cmd_utils.py", line 157, in wait_and_check_returncode
    raise RuntimeError(error_message)
RuntimeError: Command failed(8356, 1): /usr/bin/arecord -d 3 -c 1 -f S16_LE -r 44100 -D plughw:1,0 /tmp/tmpUqdx5l