Comment 16 for bug 1246929

it is pulseaudio call snd_pcm_start at

37.726| 0.000) I: [alsa-sink-Intel ICH] alsa-sink.c: Starting playback.

so you have to find out why pulseaudio did not write any data to alsa-sink

( 37.702| 32.638) I: [pulseaudio] client.c: Created 3 "Native client (UNIX socket client)"
( 37.704| 0.002) I: [pulseaudio] client.c: Freed 3 "Native client (UNIX socket client)"
( 37.704| 0.000) I: [pulseaudio] protocol-native.c: Connection died.
( 37.712| 0.007) I: [pulseaudio] client.c: Created 4 "Native client (UNIX socket client)"
( 37.721| 0.008) D: [pulseaudio] protocol-native.c: Protocol version: remote 28, local 28
( 37.721| 0.000) I: [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
( 37.721| 0.000) D: [pulseaudio] protocol-native.c: SHM possible: yes
( 37.721| 0.000) D: [pulseaudio] protocol-native.c: Negotiated SHM: yes
( 37.721| 0.000) D: [pulseaudio] module-augment-properties.c: Looking for .desktop file for plugin-container
( 37.725| 0.003) D: [pulseaudio] module-intended-roles.c: Not setting device for stream ALSA Playback, because it lacks role.
( 37.725| 0.000) D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_04.0.analog-stereo becomes busy, resuming.
( 37.725| 0.000) D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.pci-0000_00_04.0.analog-stereo is 0x0000, resuming
( 37.726| 0.000) D: [pulseaudio] reserve-wrap.c: Successfully acquired reservation lock on device 'Audio0'
( 37.726| 0.000) I: [alsa-sink-Intel ICH] alsa-sink.c: Trying resume...
( 37.726| 0.000) D: [alsa-sink-Intel ICH] alsa-util.c: Maximum hw buffer size is 371 ms
( 37.726| 0.000) D: [alsa-sink-Intel ICH] alsa-util.c: Set buffer size first (to 3528 samples), period size second (to 441 samples).
( 37.726| 0.000) D: [alsa-sink-Intel ICH] alsa-sink.c: hwbuf_unused=0
( 37.726| 0.000) D: [alsa-sink-Intel ICH] alsa-sink.c: setting avail_min=1
( 37.726| 0.000) I: [alsa-sink-Intel ICH] alsa-sink.c: Resumed successfully...
( 37.726| 0.000) I: [alsa-sink-Intel ICH] alsa-sink.c: Starting playback.
( 37.726| 0.000) D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_04.0.analog-stereo becomes idle, timeout in 5 seconds.
( 37.727| 0.000) D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
( 37.727| 0.000) D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
( 37.727| 0.000) I: [pulseaudio] sink-input.c: Created input 0 "ALSA Playback" on alsa_output.pci-0000_00_04.0.analog-stereo with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
( 37.727| 0.000) I: [pulseaudio] sink-input.c: media.name = "ALSA Playback"
( 37.727| 0.000) I: [pulseaudio] sink-input.c: application.name = "ALSA plug-in [plugin-container]"
( 37.727| 0.000) I: [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client"
( 37.727| 0.000) I: [pulseaudio] sink-input.c: native-protocol.version = "28"
( 37.727| 0.000) I: [pulseaudio] sink-input.c: application.process.id = "2232"
( 37.727| 0.000) I: [pulseaudio] sink-input.c: application.process.user = "bmullan"
( 37.727| 0.000) I: [pulseaudio] sink-input.c: application.process.host = "bmullan-virtual-machine"
( 37.727| 0.000) I: [pulseaudio] sink-input.c: application.process.binary = "plugin-container"
( 37.727| 0.000) I: [pulseaudio] sink-input.c: application.language = "en_US.UTF-8"
( 37.727| 0.000) I: [pulseaudio] sink-input.c: window.x11.display = ":0"
( 37.727| 0.000) I: [pulseaudio] sink-input.c: application.process.machine_id = "735ddae164ef1ded237a4a095272b03a"
( 37.727| 0.000) I: [pulseaudio] sink-input.c: application.process.session_id = "c2"
( 37.727| 0.000) I: [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-application-name:ALSA plug-in [plugin-container]"
( 37.727| 0.000) I: [pulseaudio] protocol-native.c: Requested tlength=500.00 ms, minreq=20.00 ms
( 37.727| 0.000) D: [pulseaudio] protocol-native.c: Early requests mode enabled, configuring sink latency to minreq.
( 37.727| 0.000) E: [alsa-sink-Intel ICH] alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write!
( 37.727| 0.000) E: [alsa-sink-Intel ICH] alsa-sink.c: Most likely this is a bug in the ALSA driver 'snd_intel8x0'. Please report this issue to the ALSA developers.
( 37.727| 0.000) E: [alsa-sink-Intel ICH] alsa-sink.c: We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail() returned 0 or another value < min_avail.