pulseaudio log

gistfile1.txt

I: [pulseaudio] client.c: Created 2 "Native client (UNIX socket client)"
D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Client added for object /org/pulseaudio/core1/client2
D: [pulseaudio] protocol-native.c: Protocol version: remote 32, local 32
I: [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
D: [pulseaudio] protocol-native.c: SHM possible: yes
D: [pulseaudio] protocol-native.c: Negotiated SHM: yes
D: [pulseaudio] protocol-native.c: Memfd possible: yes
D: [pulseaudio] protocol-native.c: Negotiated SHM type: shared memfd
D: [pulseaudio] memblock.c: Using shared memfd memory pool with 1024 slots of size 64.0 KiB each, total size is 64.0 MiB, maximum usable slot size is 65472
D: [pulseaudio] srbchannel.c: SHM block is 65472 bytes, ringbuffer capacity is 2 * 32712 bytes
D: [pulseaudio] protocol-native.c: Enabling srbchannel...
D: [pulseaudio] module-augment-properties.c: Looking for .desktop file for firefox
D: [pulseaudio] module-augment-properties.c: Found /usr/share/applications/firefox.desktop.
D: [pulseaudio] conf-parser.c: Parsing configuration file '/usr/share/applications/firefox.desktop'
D: [pulseaudio] protocol-native.c: Client enabled srbchannel.
D: [pulseaudio] module-intended-roles.c: Not setting device for stream AudioStream, because it lacks role.
D: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"float32le\""  format.rate = "48000"  format.channels = "2"  format.channel_map = "\"front-left,front-right\""
I: [pulseaudio] sink-input.c: Trying to change sample rate
D: [pulseaudio] sink.c: Suspending sink alsa_output.pci-0000_00_1f.3.analog-stereo due to changing format.
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: suspend_cause: IDLE -> IDLE|INTERNAL
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1f.3.analog-stereo.monitor: suspend_cause: IDLE -> IDLE|INTERNAL
I: [pulseaudio] alsa-sink.c: Updating rate for device front:0, new rate is 48000
D: [pulseaudio] source.c: Suspending source alsa_output.pci-0000_00_1f.3.analog-stereo.monitor due to changing the sample rate.
I: [pulseaudio] source.c: Changed sampling rate successfully
I: [pulseaudio] sink.c: Changed format successfully
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: suspend_cause: IDLE|INTERNAL -> IDLE
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1f.3.analog-stereo.monitor: suspend_cause: IDLE|INTERNAL -> IDLE
I: [pulseaudio] sink-input.c: Rate changed to 48000 Hz
I: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-application-name:AudioIPC Server.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1f.3.analog-stereo becomes busy, resuming.
D: [pulseaudio] reserve-wrap.c: Successfully acquired reservation lock on device 'Audio0'
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Trying resume...
I: [alsa-sink-ALC269VC Analog] alsa-util.c: Trying to disable ALSA period wakeups, using timers only
D: [alsa-sink-ALC269VC Analog] alsa-util.c: Maximum hw buffer size is 21845 ms
D: [alsa-sink-ALC269VC Analog] alsa-util.c: Set buffer size first (to 88200 samples), period size second (to 44100 samples).
I: [alsa-sink-ALC269VC Analog] alsa-util.c: ALSA period wakeups disabled
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=0
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87319
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=0
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87319
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=0
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87319
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Time scheduling watermark is 18.38ms
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Resumed successfully...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: suspend_cause: IDLE -> (none)
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: state: SUSPENDED -> IDLE
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1f.3.analog-stereo becomes idle, timeout in 5 seconds.
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Starting playback.
D: [alsa-sink-ALC269VC Analog] ratelimit.c: 283 events suppressed
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1f.3.analog-stereo.monitor: suspend_cause: IDLE -> (none)
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1f.3.analog-stereo.monitor: state: SUSPENDED -> IDLE
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1f.3.analog-stereo becomes idle, timeout in 5 seconds.
I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
D: [pulseaudio] resampler.c: Resampler:
D: [pulseaudio] resampler.c:   rate 48000 -> 48000 (method copy)
D: [pulseaudio] resampler.c:   format float32le -> s16le (intermediate s16le)
D: [pulseaudio] resampler.c:   channels 2 -> 2 (resampling 2)
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
I: [pulseaudio] sink-input.c: Created input 0 "AudioStream" on alsa_output.pci-0000_00_1f.3.analog-stereo with sample spec float32le 2ch 48000Hz and channel map front-left,front-right
I: [pulseaudio] sink-input.c:     media.name = "AudioStream"
I: [pulseaudio] sink-input.c:     application.name = "AudioIPC Server"
I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
I: [pulseaudio] sink-input.c:     native-protocol.version = "32"
I: [pulseaudio] sink-input.c:     application.process.id = "5052"
I: [pulseaudio] sink-input.c:     application.process.user = "eris"
I: [pulseaudio] sink-input.c:     application.process.host = "STARFUCKER"
I: [pulseaudio] sink-input.c:     application.process.binary = "firefox"
I: [pulseaudio] sink-input.c:     application.language = "en_US.UTF-8"
I: [pulseaudio] sink-input.c:     window.x11.display = ":0"
I: [pulseaudio] sink-input.c:     application.process.machine_id = "6ee4bee20e3d4943972bcfdbe1d4a0d0"
I: [pulseaudio] sink-input.c:     application.process.session_id = "c4"
I: [pulseaudio] sink-input.c:     application.icon_name = "firefox"
I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:AudioIPC Server"
I: [pulseaudio] protocol-native.c: Requested tlength=100.00 ms, minreq=25.00 ms
D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] protocol-native.c: Requested latency=25.00 ms, Received latency=25.00 ms
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=28800, base=8, prebuf=19208, minreq=9600 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=28800, base=8, prebuf=19208, minreq=9600 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 100.00 ms = 25.00 ms + 2*25.00 ms + 25.00 ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 25.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=348000
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87481
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Requesting rewind due to latency change.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Requested volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Got hardware volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Calculated software volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB (accurate-enough=yes)
D: [alsa-sink-ALC269VC Analog] sink.c: Volume not changing
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Limited to 352544 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: before: 88136
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: after: 88136
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Rewound 352544 bytes.
D: [alsa-sink-ALC269VC Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC269VC Analog] sink.c: latency = 148
D: [alsa-sink-ALC269VC Analog] sink-input.c: Have to rewind 352544 bytes on render memblockq.
D: [alsa-sink-ALC269VC Analog] source.c: Processing rewind...
D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream0
D: [pulseaudio] protocol-native.c: Client firefox changes volume of sink input AudioStream.
D: [alsa-sink-ALC269VC Analog] sink-input.c: Requesting rewind due to uncorking
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Requested to rewind 4800 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Limited to 4544 bytes.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1f.3.analog-stereo becomes busy, resuming.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: before: 1136
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: after: 1136
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Rewound 4544 bytes.
D: [alsa-sink-ALC269VC Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC269VC Analog] sink.c: latency = 0
D: [alsa-sink-ALC269VC Analog] source.c: Processing rewind...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: state: IDLE -> RUNNING
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Requested to rewind 4800 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Limited to 4544 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: before: 1136
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: after: 1136
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Rewound 4544 bytes.
D: [alsa-sink-ALC269VC Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC269VC Analog] sink.c: latency = 0
D: [alsa-sink-ALC269VC Analog] sink-input.c: Have to rewind 4544 bytes on render memblockq.
D: [alsa-sink-ALC269VC Analog] source.c: Processing rewind...
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Underrun!
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Increasing minimal latency to 1.00 ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 25.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=348000
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87481
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 25.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=348000
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87481
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Implicit underrun of 'AudioStream'
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Requested to rewind 4800 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Limited to 4544 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: before: 1136
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: after: 1136
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Rewound 4544 bytes.
D: [alsa-sink-ALC269VC Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC269VC Analog] sink.c: latency = 922888
D: [alsa-sink-ALC269VC Analog] sink-input.c: Have to rewind 4544 bytes on render memblockq.
D: [alsa-sink-ALC269VC Analog] source.c: Processing rewind...
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Underrun!
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Increasing minimal latency to 2.00 ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 25.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=348000
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87481
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 25.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=348000
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87481
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Implicit underrun of 'AudioStream'
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Requested to rewind 4800 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Limited to 4544 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: before: 1136
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: after: 1136
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Rewound 4544 bytes.
D: [alsa-sink-ALC269VC Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC269VC Analog] sink.c: latency = 929584
D: [alsa-sink-ALC269VC Analog] sink-input.c: Have to rewind 4544 bytes on render memblockq.
D: [alsa-sink-ALC269VC Analog] source.c: Processing rewind...
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Underrun!
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Increasing minimal latency to 4.00 ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 25.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=348000
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87481
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 25.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=348000
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87481
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Implicit underrun of 'AudioStream'
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Requested to rewind 4800 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Limited to 4544 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: before: 1136
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: after: 1136
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Rewound 4544 bytes.
D: [alsa-sink-ALC269VC Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC269VC Analog] sink.c: latency = 925183
D: [alsa-sink-ALC269VC Analog] sink-input.c: Have to rewind 4544 bytes on render memblockq.
D: [alsa-sink-ALC269VC Analog] source.c: Processing rewind...
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Underrun!
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Increasing minimal latency to 8.00 ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 25.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=348000
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87481
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 25.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=348000
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87481
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Implicit underrun of 'AudioStream'
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Requested to rewind 4800 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Limited to 4544 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: before: 1136
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: after: 1136
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Rewound 4544 bytes.
D: [alsa-sink-ALC269VC Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC269VC Analog] sink.c: latency = 19282
D: [alsa-sink-ALC269VC Analog] sink-input.c: Have to rewind 4544 bytes on render memblockq.
D: [alsa-sink-ALC269VC Analog] source.c: Processing rewind...
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Underrun!
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Increasing minimal latency to 16.00 ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 25.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=348000
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87481
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 25.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=348000
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87481
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Implicit underrun of 'AudioStream'
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Requested to rewind 4800 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Limited to 4544 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: before: 1136
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: after: 1136
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Rewound 4544 bytes.
D: [alsa-sink-ALC269VC Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC269VC Analog] sink.c: latency = 0
D: [alsa-sink-ALC269VC Analog] sink-input.c: Have to rewind 4544 bytes on render memblockq.
D: [alsa-sink-ALC269VC Analog] source.c: Processing rewind...
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Underrun!
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Increasing minimal latency to 26.00 ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 26.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=347808
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87481
D: [alsa-sink-ALC269VC Analog] protocol-native.c: max_request changed, trying to update from 28800 to 29184.
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Notifying client about increased tlength
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 26.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=347808
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87481
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Implicit underrun of 'AudioStream'
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Requested to rewind 4992 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Limited to 4736 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: before: 1184
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: after: 1184
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Rewound 4736 bytes.
D: [alsa-sink-ALC269VC Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC269VC Analog] sink.c: latency = 2476
D: [alsa-sink-ALC269VC Analog] sink-input.c: Have to rewind 4736 bytes on render memblockq.
D: [alsa-sink-ALC269VC Analog] source.c: Processing rewind...
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Underrun!
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Increasing wakeup watermark to 16.00 ms
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Implicit underrun of 'AudioStream'
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Requested to rewind 4992 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Limited to 4736 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: before: 1184
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: after: 1184
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Rewound 4736 bytes.
D: [alsa-sink-ALC269VC Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC269VC Analog] sink.c: latency = 0
D: [alsa-sink-ALC269VC Analog] sink-input.c: Have to rewind 4736 bytes on render memblockq.
D: [alsa-sink-ALC269VC Analog] source.c: Processing rewind...
D: [alsa-sink-ALC269VC Analog] sink-input.c: Requesting rewind due to corking
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Requested to rewind 4736 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Limited to 4736 bytes.
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: before: 1184
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: after: 1184
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Rewound 4736 bytes.
D: [alsa-sink-ALC269VC Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC269VC Analog] sink.c: latency = 0
D: [alsa-sink-ALC269VC Analog] sink-input.c: Have to rewind 4736 bytes on render memblockq.
D: [alsa-sink-ALC269VC Analog] sink-input.c: Have to rewind 9472 bytes on implementor.
D: [alsa-sink-ALC269VC Analog] source.c: Processing rewind...
D: [alsa-sink-ALC269VC Analog] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: Found underrun 4736 bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-ALC269VC Analog] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: Found underrun 4736 bytes ago (256 bytes ahead in playback buffer)
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: state: RUNNING -> IDLE
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1f.3.analog-stereo becomes idle, timeout in 5 seconds.
D: [alsa-sink-ALC269VC Analog] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: Found underrun 4736 bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-ALC269VC Analog] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: Found underrun 4736 bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-ALC269VC Analog] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: Found underrun 4736 bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-ALC269VC Analog] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: Found underrun 4736 bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-ALC269VC Analog] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: Found underrun 4736 bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-ALC269VC Analog] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: Found underrun 4736 bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-ALC269VC Analog] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: Found underrun 4736 bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-ALC269VC Analog] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: Found underrun 4736 bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-ALC269VC Analog] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: Found underrun 4736 bytes ago (256 bytes ahead in playback buffer)
D: [alsa-sink-ALC269VC Analog] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: Found underrun 4736 bytes ago (256 bytes ahead in playback buffer)
--- 8< --- (above line repeats 1060 more times) --- 8< ---
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Underrun!
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Increasing minimal latency to 36.00 ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 36.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=345888
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87433
D: [alsa-sink-ALC269VC Analog] protocol-native.c: max_request changed, trying to update from 29184 to 33024.
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Notifying client about increased tlength
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 36.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=345888
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=87433
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Underrun!
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Increasing wakeup watermark to 26.00 ms
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Underrun!
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Increasing minimal latency to 46.00 ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 46.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=343968
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=86953
D: [alsa-sink-ALC269VC Analog] protocol-native.c: max_request changed, trying to update from 33024 to 36864.
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Notifying client about increased tlength
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 46.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=343968
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=86953
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Underrun!
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Increasing wakeup watermark to 36.00 ms
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Underrun!
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Increasing minimal latency to 56.00 ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 56.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=342048
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=86473
D: [alsa-sink-ALC269VC Analog] protocol-native.c: max_request changed, trying to update from 36864 to 40704.
D: [alsa-sink-ALC269VC Analog] protocol-native.c: Notifying client about increased tlength
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: Latency set to 56.00ms
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: hwbuf_unused=342048
D: [alsa-sink-ALC269VC Analog] alsa-sink.c: setting avail_min=86473
I: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1f.3.analog-stereo idle for too long, suspending ...
I: [alsa-sink-ALC269VC Analog] alsa-sink.c: Device suspended...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: suspend_cause: (none) -> IDLE
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1f.3.analog-stereo: state: IDLE -> SUSPENDED
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1f.3.analog-stereo.monitor: suspend_cause: (none) -> IDLE
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1f.3.analog-stereo.monitor: state: IDLE -> SUSPENDED
I: [pulseaudio] core.c: All sinks and sources are suspended, vacuuming memory
D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC0 is accessible: yes
D: [pulseaudio] module-udev-detect.c: Resuming all sinks and sources of card alsa_card.pci-0000_00_1f.3.