[pulseaudio-discuss] No sound with PA after muting the master channel and restarting PA

Tue May 26 09:17:03 PDT 2009

Hi,

First of all here's a bug reported by someone else in debian bug
tracking system:
http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=525572

Here's the summary:

1) Start pulseaudio (per-user),
2) Play something, it works, nice.
3) Mute the master channel using KMix or amixer, doesn't matter:
    amixer -c0 set Master 90% mute
4) Play something, no sound, okay.
5) Stop PA using pulseaudio -k and restart it.
6) The channel is muted so unmute it:
    amixer -c0 set Master 90% unmute
7) Play something, still no sound, problem! The channel is actually
unmuted when looked over alsamixer, but PA is silent.
8) call set-sink-mute 0 0 from pacmd, and it works again.

Note that:

 - The workaround suggested in the bug report(unloading
module-device-store) doesn't work,
 - Removing .pulse has no effect,
 - I can reproduce the issue on every box I tried.

Here's the daemon's output (-vvv):

(Muted the channel with amixer and killed the daemon)

I: module-suspend-on-idle.c: Source alsa_input.pci_10de_ac0_sound_card_0
idle for too long, suspending ...
I: alsa-source.c: Device suspended...
I: module-suspend-on-idle.c: Sink alsa_output.pci_10de_ac0_sound_card_0
idle for too long, suspending ...
I: alsa-sink.c: Device suspended...
D: alsa-sink.c: Read hardware volume: 0:  93% 1:  93%
D: alsa-sink.c: Read hardware volume: 0:  93% 1:  93%
I: module-device-restore.c: Storing volume/mute for device
sink:alsa_output.pci_10de_ac0_sound_card_0.
D: module-hal-detect.c: dbus: interface=org.freedesktop.Hal.Device,
path=/org/freedesktop/Hal/devices/computer_power_supply_battery_BAT0,
member=PropertyModified
D: bluetooth-util.c: dbus: interface=org.freedesktop.Hal.Device,
path=/org/freedesktop/Hal/devices/computer_power_supply_battery_BAT0,
member=PropertyModified
D: module-console-kit.c: dbus: interface=org.freedesktop.Hal.Device,
path=/org/freedesktop/Hal/devices/computer_power_supply_battery_BAT0,
member=PropertyModified
I: module-device-restore.c: Synced.

(Restarted it)
I: module-device-restore.c: Sucessfully opened database file
'/home/ozan/.pulse/1cf2716bedc98e258fb135c24a015dec:device-volumes.i686-pc-linux-gnu.gdbm'.
I: module.c: Loaded "module-device-restore" (index: #0; argument: "").
I: module-stream-restore.c: Sucessfully opened database file
'/home/ozan/.pulse/1cf2716bedc98e258fb135c24a015dec:stream-volumes.i686-pc-linux-gnu.gdbm'.
I: module.c: Loaded "module-stream-restore" (index: #1; argument: "").
I: module-card-restore.c: Sucessfully opened database file
'/home/ozan/.pulse/1cf2716bedc98e258fb135c24a015dec:card-database.i686-pc-linux-gnu.gdbm'.
I: module.c: Loaded "module-card-restore" (index: #2; argument: "").
I: module.c: Loaded "module-augment-properties" (index: #3; argument: "").
..
..
I: module-device-restore.c: Restoring volume for sink
alsa_output.pci_10de_ac0_sound_card_0.
I: module-device-restore.c: Restoring mute state for sink
alsa_output.pci_10de_ac0_sound_card_0.

(Unmuted the channel with amixer)
D: alsa-sink.c: Read hardware volume: 0:  93% 1:  93%
D: alsa-sink.c: Read hardware volume: 0:  93% 1:  93%
I: module-device-restore.c: Storing volume/mute for device
sink:alsa_output.pci_10de_ac0_sound_card_0.

(Play something)
I: alsa-sink.c: Trying resume...
D: alsa-util.c: Maximum hw buffer size is 371 ms
D: alsa-sink.c: Latency set to 250.00ms
D: alsa-sink.c: hwbuf_unused=21436
D: alsa-sink.c: setting avail_min=15503
I: alsa-sink.c: Resumed successfully...
D: module-suspend-on-idle.c: Sink alsa_output.pci_10de_ac0_sound_card_0
becomes idle.
I: alsa-sink.c: Starting playback.
D: alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: module-suspend-on-idle.c: Sink alsa_output.pci_10de_ac0_sound_card_0
becomes busy.
D: alsa-sink.c: Cutting sleep time for the initial iterations by half.
I: resampler.c: Using resampler 'speex-float-0'
I: resampler.c: Using float32le as working format.
I: resampler.c: Choosing speex quality setting 0.
D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0,
base=4, prebuf=0, minreq=1 maxrewind=0
D: memblockq.c: memblockq sanitized: maxlength=33554432,
tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
I: sink-input.c: Created input 0 "audio stream" on
alsa_output.pci_10de_ac0_sound_card_0 with sample spec s16le 2ch 48000Hz
and channel map front-left,front-right
I: protocol-native.c: Requested tlength=250.00 ms, minreq=20.00 ms
D: protocol-native.c: Traditional mode enabled, modifying sink usec only
for compat with minreq.
D: alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: memblockq.c: memblockq requested: maxlength=4194304, tlength=48000,
base=4, prebuf=44164, minreq=3840 maxrewind=0
D: memblockq.c: memblockq sanitized: maxlength=4194304, tlength=48000,
base=4, prebuf=44164, minreq=3840 maxrewind=0
I: protocol-native.c: Final latency 460.00 ms = 210.00 ms + 2*20.00 ms +
210.00 ms
D: alsa-sink.c: Requested volume: 0:  93% 1:  93%
D: alsa-sink.c: Got hardware volume: 0:  94% 1:  94%
D: alsa-sink.c: Calculated software volume: 0:  98% 1:  98%
D: core-subscribe.c: Dropped redundant event due to change event.
D: alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: alsa-sink.c: Latency set to 210.00ms
D: alsa-sink.c: hwbuf_unused=28492
D: alsa-sink.c: setting avail_min=15503
D: alsa-sink.c: Requesting rewind due to latency change.
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 40488 bytes.
D: alsa-sink.c: before: 10122
D: alsa-sink.c: after: 10122
D: alsa-sink.c: Rewound 40488 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 40488 bytes on render memblockq.
D: source.c: Processing rewind...
D: core-subscribe.c: Dropped redundant event due to change event.
D: protocol-native.c: Requesting rewind due to end of underrun.
D: alsa-sink.c: Requested volume: 0:  93% 1:  93%
D: alsa-sink.c: Got hardware volume: 0:  94% 1:  94%
D: alsa-sink.c: Calculated software volume: 0:  98% 1:  98%
D: alsa-sink.c: hwbuf_unused=0
D: alsa-sink.c: setting avail_min=15502
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: module-suspend-on-idle.c: Sink alsa_output.pci_10de_ac0_sound_card_0
becomes idle.
D: alsa-sink.c: Limited to 31908 bytes.
D: alsa-sink.c: before: 7977
D: alsa-sink.c: after: 7977
D: alsa-sink.c: Rewound 31908 bytes.
D: sink.c: Processing rewind...
D: source.c: Processing rewind...
D: module-suspend-on-idle.c: Sink alsa_output.pci_10de_ac0_sound_card_0
becomes idle.
D: core-subscribe.c: Dropped redundant event due to change event.
D: core.c: Hmm, no streams around, trying to vacuum.
I: sink-input.c: Freeing input 0 "audio stream"
I: client.c: Freed 1 "MPlayer"
I: protocol-native.c: Connection died.

(call set-sink-mute 0 0 from pacmd)
I: main.c: Got signal SIGUSR2.
I: module.c: Loaded "module-cli-protocol-unix" (index: #17; argument: "").
I: client.c: Created 2 "UNIX socket client"
D: cli.c: CLI got EOF from user.
I: client.c: Freed 2 "UNIX socket client"
I: client.c: Created 3 "Native client (UNIX socket client)"
D: protocol-native.c: Protocol version: remote 15, local 15
I: protocol-native.c: Got credentials: uid=1000 gid=100 success=1
D: protocol-native.c: SHM possible: yes
D: protocol-native.c: Negotiated SHM: yes
D: module-augment-properties.c: Looking for .desktop file for mplayer
D: reserve-wrap.c: Successfully acquired reservation lock on device 'Audio0'
I: alsa-sink.c: Trying resume...
D: alsa-util.c: Maximum hw buffer size is 371 ms
D: alsa-sink.c: hwbuf_unused=0
D: alsa-sink.c: setting avail_min=15502
I: alsa-sink.c: Resumed successfully...
I: alsa-sink.c: Starting playback.
D: alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: module-suspend-on-idle.c: Sink alsa_output.pci_10de_ac0_sound_card_0
becomes idle.
D: alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: module-suspend-on-idle.c: Sink alsa_output.pci_10de_ac0_sound_card_0
becomes busy.
I: resampler.c: Using resampler 'speex-float-0'
I: resampler.c: Using float32le as working format.
I: resampler.c: Choosing speex quality setting 0.
D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0,
base=4, prebuf=0, minreq=1 maxrewind=0
D: memblockq.c: memblockq sanitized: maxlength=33554432,
tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
I: sink-input.c: Created input 1 "audio stream" on
alsa_output.pci_10de_ac0_sound_card_0 with sample spec s16le 2ch 48000Hz
and channel map front-left,front-right
I: protocol-native.c: Requested tlength=250.00 ms, minreq=20.00 ms
D: protocol-native.c: Traditional mode enabled, modifying sink usec only
for compat with minreq.
D: alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: memblockq.c: memblockq requested: maxlength=4194304, tlength=48000,
base=4, prebuf=44164, minreq=3840 maxrewind=0
D: memblockq.c: memblockq sanitized: maxlength=4194304, tlength=48000,
base=4, prebuf=44164, minreq=3840 maxrewind=0
I: protocol-native.c: Final latency 460.00 ms = 210.00 ms + 2*20.00 ms +
210.00 ms
D: alsa-sink.c: Requested volume: 0:  93% 1:  93%
D: alsa-sink.c: Got hardware volume: 0:  94% 1:  94%
D: alsa-sink.c: Calculated software volume: 0:  98% 1:  98%
D: core-subscribe.c: Dropped redundant event due to change event.
D: alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: alsa-sink.c: Latency set to 210.00ms
D: alsa-sink.c: hwbuf_unused=28492
D: alsa-sink.c: setting avail_min=15503
D: alsa-sink.c: Requesting rewind due to latency change.
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 61876 bytes.
D: alsa-sink.c: before: 15469
D: alsa-sink.c: after: 15469
D: alsa-sink.c: Rewound 61876 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 61876 bytes on render memblockq.
D: source.c: Processing rewind...
D: core-subscribe.c: Dropped redundant event due to change event.
D: protocol-native.c: Requesting rewind due to end of underrun.
D: alsa-sink.c: Requested volume: 0:  93% 1:  93%
D: alsa-sink.c: Got hardware volume: 0:  94% 1:  94%
D: alsa-sink.c: Calculated software volume: 0:  98% 1:  98%
D: alsa-sink.c: hwbuf_unused=0
D: alsa-sink.c: setting avail_min=15502
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: module-suspend-on-idle.c: Sink alsa_output.pci_10de_ac0_sound_card_0
becomes idle.
D: alsa-sink.c: Limited to 32572 bytes.
D: alsa-sink.c: before: 8143
D: alsa-sink.c: after: 8143
D: alsa-sink.c: Rewound 32572 bytes.
D: sink.c: Processing rewind...
D: source.c: Processing rewind...
D: module-suspend-on-idle.c: Sink alsa_output.pci_10de_ac0_sound_card_0
becomes idle.
D: core-subscribe.c: Dropped redundant event due to change event.
D: core.c: Hmm, no streams around, trying to vacuum.
I: sink-input.c: Freeing input 1 "audio stream"
I: client.c: Freed 3 "MPlayer"
I: protocol-native.c: Connection died.