Oct 16 12:19:33 auxiliary pulseaudio[3357]: [pulseaudio] cli.c: CLI got EOF from user. Oct 16 12:19:33 auxiliary pulseaudio[3357]: [pulseaudio] client.c: Freed 142 "UNIX socket client" Oct 16 12:19:33 auxiliary pulseaudio[3357]: [pulseaudio] client.c: Created 143 "UNIX socket client" Oct 16 12:19:33 auxiliary pulseaudio[3357]: ( 0.000| 0.000) [pulseaudio] cli.c: CLI got EOF from user. Oct 16 12:19:33 auxiliary pulseaudio[3357]: ( 0.000| 0.000) [pulseaudio] client.c: Freed 143 "UNIX socket client" Oct 16 12:20:06 auxiliary pulseaudio[3357]: ( 33.164| 33.164) [pulseaudio] module-x11-bell.c: Ringing bell failed, reverting to X11 device bell. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.105| 12.940) [pulseaudio] client.c: Created 144 "Native client (UNIX socket client)" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.105| 0.000) [pulseaudio] protocol-native.c: Protocol version: remote 26, local 26 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.105| 0.000) [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.105| 0.000) [pulseaudio] protocol-native.c: SHM possible: yes Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.105| 0.000) [pulseaudio] protocol-native.c: Negotiated SHM: yes Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.105| 0.000) [pulseaudio] module-augment-properties.c: Looking for .desktop file for vlc Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.105| 0.000) [pulseaudio] module-stream-restore.c: Restoring device for stream sink-input-by-application-id:org.VideoLAN.VLC. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.105| 0.000) [pulseaudio] module-intended-roles.c: Not setting device for stream audio stream, because already set. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.105| 0.000) [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-application-id:org.VideoLAN.VLC. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.105| 0.000) [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-application-id:org.VideoLAN.VLC. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.105| 0.000) [pulseaudio] sink.c: Suspend cause of sink alsa_output.pci-0000_00_1b.0.analog-surround-51 is 0x0000, resuming Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] reserve-wrap.c: Failed to acquire reservation lock on device 'Audio0': Input/output error Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [alsa-sink] alsa-sink.c: Trying resume... Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [alsa-sink] alsa-util.c: Trying to disable ALSA period wakeups, using timers only Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [alsa-sink] alsa-util.c: Maximum hw buffer size is 123 ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [alsa-sink] alsa-util.c: Set buffer size first (to 5460 samples), period size second (to 2730 samples). Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [alsa-sink] alsa-util.c: ALSA period wakeups disabled Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=0 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5165 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [alsa-sink] alsa-sink.c: Time scheduling watermark is 6.69ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [alsa-sink] alsa-sink.c: Resumed successfully... Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-surround-51 becomes idle, timeout in 5 seconds. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [alsa-sink] alsa-sink.c: Starting playback. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-surround-51 becomes busy. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] resampler.c: Channel matrix: Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] resampler.c: I00 I01 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] resampler.c: +------------ Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] resampler.c: O00 | 1.000 0.000 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] resampler.c: O01 | 0.000 1.000 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] resampler.c: O02 | 1.000 0.000 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] resampler.c: O03 | 0.000 1.000 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] resampler.c: O04 | 0.500 0.500 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] resampler.c: O05 | 0.000 0.000 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] remap.c: Using generic matrix remapping Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] resampler.c: Using resampler 'speex-float-1' Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] resampler.c: Using float32le as working format. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] resampler.c: Choosing speex quality setting 1. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=12, prebuf=0, minreq=1 maxrewind=0 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.106| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554436, tlength=33554436, base=12, prebuf=0, minreq=12 maxrewind=0 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: Created input 78 "audio stream" on alsa_output.pci-0000_00_1b.0.analog-surround-51 with sample spec float32le 2ch 44100Hz and channel map front-left,front-right Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: media.name = "audio stream" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: application.name = "VLC media player" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: native-protocol.version = "26" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: application.id = "org.VideoLAN.VLC" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: application.version = "2.0.3" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: application.icon_name = "vlc" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: application.language = "en_US.UTF-8" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: application.process.id = "8741" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: application.process.user = "ak" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: application.process.host = "auxiliary" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: application.process.machine_id = "295b79c04f9b22eebdba04f450675a47" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: application.process.session_id = "295b79c04f9b22eebdba04f450675a47-1350405294.13748-1324813371" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: application.process.binary = "vlc" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: window.x11.display = ":0" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-application-id:org.VideoLAN.VLC" Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] protocol-native.c: Requested tlength=40.00 ms, minreq=20.00 ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] protocol-native.c: Traditional mode enabled, modifying sink usec only for compat with minreq. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] protocol-native.c: Requested latency=0.00 ms, Received latency=0.50 ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=14296, base=8, prebuf=0, minreq=7056 maxrewind=0 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=14296, base=8, prebuf=0, minreq=7056 maxrewind=0 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [pulseaudio] protocol-native.c: Final latency 41.02 ms = 0.52 ms + 2*20.00 ms + 0.50 ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: Latency set to 0.50ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=65256 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5450 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: Requesting rewind due to latency change. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 52% 1: 62% 2: 44% 3: 52% 4: 62% 5: 25% Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -16.95 dB 1: -12.55 dB 2: -21.65 dB 3: -17.25 dB 4: -12.55 dB 5: -35.87 dB Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 52% 1: 63% 2: 44% 3: 52% 4: 63% 5: 26% Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -16.90 dB 1: -12.00 dB 2: -21.40 dB 3: -17.25 dB 4: -12.00 dB 5: -35.25 dB Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 98% 2: 99% 3: 100% 4: 98% 5: 98% (accurate-enough=no) Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0.05 dB 1: -0.55 dB 2: -0.25 dB 3: -0.00 dB 4: -0.55 dB 5: -0.62 dB Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] sink.c: Volume not changing Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 65520 bytes. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: Limited to 64688 bytes. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: before: 5390 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: after: 5390 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: Rewound 64680 bytes. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] sink.c: latency = 1026 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] sink-input.c: Have to rewind 64680 bytes on render memblockq. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] source.c: Processing rewind... Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 16 12:20:19 pulseaudio[3357]: last message repeated 5 times Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: Increasing minimal latency to 1.00 ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: Latency set to 1.00ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=64992 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5439 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] protocol-native.c: max_request changed, trying to update from 14296 to 14464. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] protocol-native.c: Notifying client about increased tlength Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: Latency set to 1.00ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=64992 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.107| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5439 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.110| 0.002) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.111| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.111| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.112| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:19 pulseaudio[3357]: last message repeated 2 times Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.113| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.113| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.114| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.115| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.124| 0.009) [alsa-sink] alsa-sink.c: Increasing minimal latency to 2.00 ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.124| 0.000) [alsa-sink] alsa-sink.c: Latency set to 2.00ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.124| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=64464 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.124| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5417 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.124| 0.000) [alsa-sink] protocol-native.c: max_request changed, trying to update from 14464 to 14816. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.124| 0.000) [alsa-sink] protocol-native.c: Notifying client about increased tlength Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.124| 0.000) [alsa-sink] alsa-sink.c: Latency set to 2.00ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.124| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=64464 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.124| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5417 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.124| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.160| 0.035) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.181| 0.021) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.181| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.197| 0.015) [pulseaudio] sink.c: Suspend cause of sink alsa_output.pci-0000_00_1b.0.analog-surround-51 is 0x0004, suspending Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.197| 0.000) [alsa-sink] alsa-sink.c: Device suspended... Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.197| 0.000) [pulseaudio] alsa-sink.c: Updating rate for device surround51:0, new rate is 44100 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.197| 0.000) [pulseaudio] sink.c: Changed sampling rate successfully Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.197| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.197| 0.000) [alsa-sink] sink-input.c: Requesting rewind due to uncorking Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.197| 0.000) [pulseaudio] sink.c: Suspend cause of sink alsa_output.pci-0000_00_1b.0.analog-surround-51 is 0x0000, resuming Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.198| 0.000) [pulseaudio] reserve-wrap.c: Failed to acquire reservation lock on device 'Audio0': Input/output error Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.198| 0.000) [alsa-sink] alsa-sink.c: Trying resume... Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.198| 0.000) [alsa-sink] alsa-util.c: Trying to disable ALSA period wakeups, using timers only Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.198| 0.000) [alsa-sink] alsa-util.c: Maximum hw buffer size is 123 ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-util.c: Set buffer size first (to 5460 samples), period size second (to 2730 samples). Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-util.c: ALSA period wakeups disabled Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: Latency set to 2.00ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=64464 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5417 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: Latency set to 0.50ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=65256 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5450 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: Requesting rewind due to latency change. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: Latency set to 0.50ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=65256 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5450 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: Time scheduling watermark is 0.25ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: Resumed successfully... Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: Starting playback. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: Increasing minimal latency to 1.00 ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: Latency set to 1.00ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=64992 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5439 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: Latency set to 1.00ms Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=64992 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5439 Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.199| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-surround-51 becomes busy. Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.200| 0.000) [pulseaudio] module-udev-detect.c: /dev/snd/controlC0 is accessible: yes Oct 16 12:20:19 auxiliary pulseaudio[3357]: ( 46.223| 0.023) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.526| 3.303) [alsa-sink] protocol-native.c: Underrun on 'audio stream', 0 bytes in queue. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.527| 0.001) [alsa-sink] protocol-native.c: Requesting rewind due to rewrite. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.528| 0.000) [alsa-sink] sink-input.c: Requesting rewind due to corking Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.528| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-surround-51 becomes idle, timeout in 5 seconds. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.534| 0.006) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.535| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.536| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.537| 0.001) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.538| 0.001) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.539| 0.001) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.540| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.541| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.542| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.543| 0.001) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.544| 0.001) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.545| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.546| 0.001) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.547| 0.001) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.584| 0.036) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.585| 0.001) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.623| 0.037) [pulseaudio] sink.c: Suspend cause of sink alsa_output.pci-0000_00_1b.0.analog-surround-51 is 0x0004, suspending Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.623| 0.000) [alsa-sink] alsa-sink.c: Device suspended... Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.623| 0.000) [pulseaudio] alsa-sink.c: Updating rate for device surround51:0, new rate is 44100 Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.623| 0.000) [pulseaudio] sink.c: Changed sampling rate successfully Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.623| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.623| 0.000) [alsa-sink] sink-input.c: Requesting rewind due to uncorking Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.623| 0.000) [pulseaudio] sink.c: Suspend cause of sink alsa_output.pci-0000_00_1b.0.analog-surround-51 is 0x0000, resuming Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.624| 0.000) [pulseaudio] reserve-wrap.c: Failed to acquire reservation lock on device 'Audio0': Input/output error Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.624| 0.000) [alsa-sink] alsa-sink.c: Trying resume... Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.624| 0.000) [alsa-sink] alsa-util.c: Trying to disable ALSA period wakeups, using timers only Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.624| 0.000) [alsa-sink] alsa-util.c: Maximum hw buffer size is 123 ms Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.624| 0.000) [alsa-sink] alsa-util.c: Set buffer size first (to 5460 samples), period size second (to 2730 samples). Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.624| 0.000) [alsa-sink] alsa-util.c: ALSA period wakeups disabled Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.624| 0.000) [alsa-sink] alsa-sink.c: Latency set to 1.00ms Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.624| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=64992 Oct 16 12:20:22 auxiliary pulseaudio[3357]: ( 49.624| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5439 Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 51.829| 1.704) [alsa-sink] ratelimit.c: 996 events suppressed Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 51.829| 0.000) [alsa-sink] alsa-sink.c: Underrun! Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 51.829| 0.000) [alsa-sink] alsa-sink.c: Increasing minimal latency to 4.00 ms Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 51.829| 0.000) [alsa-sink] alsa-sink.c: Latency set to 4.00ms Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 51.829| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=63408 Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 51.829| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5373 Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 51.829| 0.000) [alsa-sink] protocol-native.c: max_request changed, trying to update from 14816 to 15520. Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 51.829| 0.000) [alsa-sink] protocol-native.c: Notifying client about increased tlength Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 51.829| 0.000) [alsa-sink] alsa-sink.c: Latency set to 4.00ms Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 51.829| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=63408 Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 51.829| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5373 Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.014| 0.184) [alsa-sink] alsa-sink.c: Underrun! Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.014| 0.000) [alsa-sink] alsa-sink.c: Increasing minimal latency to 8.00 ms Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.014| 0.000) [alsa-sink] alsa-sink.c: Latency set to 8.00ms Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.014| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=61296 Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.014| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5285 Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.014| 0.000) [alsa-sink] protocol-native.c: max_request changed, trying to update from 15520 to 16928. Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.014| 0.000) [alsa-sink] protocol-native.c: Notifying client about increased tlength Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.014| 0.000) [alsa-sink] alsa-sink.c: Latency set to 8.00ms Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.014| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=61296 Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.014| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5285 Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.351| 0.337) [alsa-sink] alsa-sink.c: Underrun! Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.351| 0.000) [alsa-sink] alsa-sink.c: Increasing minimal latency to 16.00 ms Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.351| 0.000) [alsa-sink] alsa-sink.c: Latency set to 16.00ms Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.351| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=57060 Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.351| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5285 Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.351| 0.000) [alsa-sink] protocol-native.c: max_request changed, trying to update from 16928 to 19752. Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.351| 0.000) [alsa-sink] protocol-native.c: Notifying client about increased tlength Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.351| 0.000) [alsa-sink] alsa-sink.c: Latency set to 16.00ms Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.351| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=57060 Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.351| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5285 Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.454| 0.102) [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry) Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.454| 0.000) [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry) Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.454| 0.000) [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry) Oct 16 12:20:25 auxiliary pulseaudio[3357]: ( 52.587| 0.133) [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry) Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 52.729| 0.141) [alsa-sink] alsa-sink.c: Underrun! Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 52.729| 0.000) [alsa-sink] alsa-sink.c: Increasing wakeup watermark to 7.98 ms Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 52.748| 0.019) [alsa-sink] alsa-sink.c: Underrun! Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 52.748| 0.000) [alsa-sink] alsa-sink.c: Increasing wakeup watermark to 8.00 ms Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 52.748| 0.000) [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry) Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 52.938| 0.189) [pulseaudio] client.c: Created 145 "Native client (UNIX socket client)" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.023| 0.084) [pulseaudio] protocol-native.c: Protocol version: remote 26, local 26 Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.023| 0.000) [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1 Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.023| 0.000) [pulseaudio] protocol-native.c: SHM possible: yes Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.023| 0.000) [pulseaudio] protocol-native.c: Negotiated SHM: yes Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.023| 0.000) [pulseaudio] module-augment-properties.c: Looking for .desktop file for gnome-control-center Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.116| 0.092) [pulseaudio] module-stream-restore.c: Not restoring device for stream source-output-by-application-id:org.gnome.VolumeControl, because already set Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.116| 0.000) [pulseaudio] module-intended-roles.c: Not setting device for stream Peak detect, because already set. Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.116| 0.000) [pulseaudio] source-output.c: Trying to change sample rate Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.116| 0.000) [pulseaudio] source-output.c: Resampling enabled to 44100 Hz Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.116| 0.000) [pulseaudio] source.c: Suspend cause of source alsa_input.pci-0000_00_1b.0.analog-stereo is 0x0000, resuming Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.116| 0.000) [alsa-source] alsa-source.c: Trying resume... Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.116| 0.000) [alsa-source] alsa-util.c: Trying to disable ALSA period wakeups, using timers only Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.116| 0.000) [alsa-source] alsa-util.c: Maximum hw buffer size is 371 ms Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.116| 0.000) [alsa-source] alsa-util.c: Set buffer size first (to 16384 samples), period size second (to 8192 samples). Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.116| 0.000) [alsa-source] alsa-util.c: ALSA period wakeups disabled Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.116| 0.000) [alsa-source] alsa-source.c: hwbuf_unused=0 Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.116| 0.000) [alsa-source] alsa-source.c: setting avail_min=15680 Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.116| 0.000) [alsa-source] alsa-source.c: hwbuf_unused=0 Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] alsa-source.c: setting avail_min=15502 Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] alsa-source.c: Time scheduling watermark is 20.00ms Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] alsa-source.c: Resumed successfully... Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] alsa-source.c: Starting capture. Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] module-suspend-on-idle.c: Source alsa_input.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds. Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] module-suspend-on-idle.c: Source alsa_input.pci-0000_00_1b.0.analog-stereo becomes busy. Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] resampler.c: Channel matrix: Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] resampler.c: I00 I01 Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] resampler.c: +------------ Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] resampler.c: O00 | 0.500 0.500 Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] remap.c: Using generic matrix remapping Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] resampler.c: Using resampler 'peaks' Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] resampler.c: Using float32le as working format. Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: Created output 56 "Peak detect" on alsa_input.pci-0000_00_1b.0.analog-stereo with sample spec float32le 1ch 25Hz and channel map mono Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: application.id = "org.gnome.VolumeControl" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: media.name = "Peak detect" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: application.name = "GNOME Volume Control Dialog" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: native-protocol.peer = "UNIX socket client" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: native-protocol.version = "26" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: application.icon_name = "multimedia-volume-control" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: application.version = "3.4.2" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: application.process.id = "8787" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: application.process.user = "ak" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: application.process.host = "auxiliary" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: application.process.binary = "gnome-control-center" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: application.language = "en_US.UTF-8" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: window.x11.display = ":0" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: application.process.machine_id = "295b79c04f9b22eebdba04f450675a47" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: application.process.session_id = "295b79c04f9b22eebdba04f450675a47-1350405294.13748-1324813371" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] source-output.c: module-stream-restore.id = "source-output-by-application-id:org.gnome.VolumeControl" Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=0, base=4, prebuf=1, minreq=0 maxrewind=0 Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=4194304, base=4, prebuf=4, minreq=4 maxrewind=0 Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] protocol-native.c: Final latency 60.00 ms = 40.00 ms + 20.00 ms Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] alsa-source.c: latency set to 20.00ms Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] alsa-source.c: hwbuf_unused=62008 Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] alsa-source.c: setting avail_min=442 Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] alsa-source.c: latency set to 20.00ms Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] alsa-source.c: hwbuf_unused=62008 Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] alsa-source.c: setting avail_min=442 Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] alsa-source.c: Requested volume: 0: 78% 1: 78% Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] alsa-source.c: in dB: 0: -6.63 dB 1: -6.63 dB Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] alsa-source.c: Got hardware volume: 0: 77% 1: 77% Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] alsa-source.c: in dB: 0: -6.75 dB 1: -6.75 dB Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] alsa-source.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] alsa-source.c: in dB: 0: 0.12 dB 1: 0.12 dB Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [alsa-source] source.c: Volume not changing Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Oct 16 12:20:26 auxiliary pulseaudio[3357]: ( 53.117| 0.000) [pulseaudio] module-stream-restore.c: Client gnome-control-center changes entry sink-input-by-media-role:event. Oct 16 12:20:27 auxiliary pulseaudio[3357]: ( 53.801| 0.683) [alsa-sink] alsa-sink.c: Underrun! Oct 16 12:20:27 auxiliary pulseaudio[3357]: ( 53.801| 0.000) [alsa-sink] alsa-sink.c: Increasing minimal latency to 26.00 ms Oct 16 12:20:27 auxiliary pulseaudio[3357]: ( 53.801| 0.000) [alsa-sink] alsa-sink.c: Latency set to 26.00ms Oct 16 12:20:27 auxiliary pulseaudio[3357]: ( 53.801| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=51768 Oct 16 12:20:27 auxiliary pulseaudio[3357]: ( 53.801| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5108 Oct 16 12:20:27 auxiliary pulseaudio[3357]: ( 53.801| 0.000) [alsa-sink] protocol-native.c: max_request changed, trying to update from 19752 to 23280. Oct 16 12:20:27 auxiliary pulseaudio[3357]: ( 53.801| 0.000) [alsa-sink] protocol-native.c: Notifying client about increased tlength Oct 16 12:20:27 auxiliary pulseaudio[3357]: ( 53.801| 0.000) [alsa-sink] alsa-sink.c: Latency set to 26.00ms Oct 16 12:20:27 auxiliary pulseaudio[3357]: ( 53.801| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=51768 Oct 16 12:20:27 auxiliary pulseaudio[3357]: ( 53.801| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=5108 Oct 16 12:20:29 auxiliary pulseaudio[3357]: ( 56.503| 2.702) [alsa-source] alsa-source.c: Increasing minimal latency to 1.00 ms Oct 16 12:20:29 auxiliary pulseaudio[3357]: ( 56.503| 0.000) [alsa-source] alsa-source.c: latency set to 20.00ms Oct 16 12:20:29 auxiliary pulseaudio[3357]: ( 56.503| 0.000) [alsa-source] alsa-source.c: hwbuf_unused=62008 Oct 16 12:20:29 auxiliary pulseaudio[3357]: ( 56.503| 0.000) [alsa-source] alsa-source.c: setting avail_min=442 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.853| 1.349) [pulseaudio] protocol-native.c: Client gnome-control-center changes volume of sink alsa_output.pci-0000_00_1b.0.analog-surround-51. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.853| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 52% 1: 62% 2: 44% 3: 52% 4: 62% 5: 25% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.853| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -16.95 dB 1: -12.55 dB 2: -21.54 dB 3: -17.15 dB 4: -12.55 dB 5: -35.87 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.853| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 52% 1: 62% 2: 44% 3: 52% 4: 63% 5: 26% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.853| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -16.90 dB 1: -12.40 dB 2: -21.40 dB 3: -16.90 dB 4: -12.00 dB 5: -35.25 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.853| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 99% 2: 99% 3: 99% 4: 98% 5: 98% (accurate-enough=no) Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.853| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0.05 dB 1: -0.15 dB 2: -0.14 dB 3: -0.25 dB 4: -0.55 dB 5: -0.62 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.853| 0.000) [alsa-sink] sink.c: Volume going down to 32727 at 10200288112 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.854| 0.000) [alsa-sink] ratelimit.c: 4 events suppressed Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.854| 0.000) [alsa-sink] sink.c: Next volume change in 25462 usec Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.854| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 65520 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.854| 0.000) [alsa-sink] alsa-sink.c: Limited to 13016 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.854| 0.000) [alsa-sink] alsa-sink.c: before: 1084 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.854| 0.000) [alsa-sink] alsa-sink.c: after: 1084 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.854| 0.000) [alsa-sink] alsa-sink.c: Rewound 13008 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.854| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.854| 0.000) [alsa-sink] sink.c: latency = 786 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.854| 0.000) [alsa-sink] sink.c: Next volume change in 785 usec Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.854| 0.000) [alsa-sink] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry) Oct 16 12:20:31 pulseaudio[3357]: last message repeated 8 times Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.854| 0.000) [alsa-sink] source.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.854| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.pci-0000_00_1b.0.analog-surround-51:analog-output. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.854| 0.000) [alsa-sink] sink.c: Volume change to 32727 at 10200263544 was written 12 usec late Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.855| 0.000) [alsa-sink] alsa-sink.c: Written HW volume did not match with the request: 0: 52% 1: 62% 2: 44% 3: 52% 4: 63% 5: 26% (request) != 0: 52% 1: 61% 2: 43% 3: 52% 4: 63% 5: 26% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.855| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -16.90 dB 1: -12.40 dB 2: -21.40 dB 3: -16.90 dB 4: -12.00 dB 5: -35.25 dB (request) != 0: -17.25 dB 1: -12.75 dB 2: -21.75 dB 3: -17.25 dB 4: -12.00 dB 5: -35.25 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.877| 0.022) [pulseaudio] protocol-native.c: Client gnome-control-center changes volume of sink alsa_output.pci-0000_00_1b.0.analog-surround-51. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.877| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 52% 1: 62% 2: 44% 3: 52% 4: 62% 5: 25% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.877| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -16.95 dB 1: -12.55 dB 2: -21.24 dB 3: -16.84 dB 4: -12.55 dB 5: -35.87 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.877| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 53% 1: 63% 2: 44% 3: 53% 4: 63% 5: 26% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.877| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -16.70 dB 1: -12.20 dB 2: -21.20 dB 3: -16.70 dB 4: -12.00 dB 5: -35.25 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.877| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 99% 1: 99% 2: 100% 3: 99% 4: 98% 5: 98% (accurate-enough=no) Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.877| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0.25 dB 1: -0.35 dB 2: -0.04 dB 3: -0.14 dB 4: -0.55 dB 5: -0.62 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.877| 0.000) [alsa-sink] sink.c: Volume going up to 32904 at 10200307340 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.877| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 65520 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.877| 0.000) [alsa-sink] alsa-sink.c: Limited to 10616 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.877| 0.000) [alsa-sink] alsa-sink.c: before: 884 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.877| 0.000) [alsa-sink] alsa-sink.c: after: 884 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.877| 0.000) [alsa-sink] alsa-sink.c: Rewound 10608 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.877| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.877| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.pci-0000_00_1b.0.analog-surround-51:analog-output. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.877| 0.000) [alsa-sink] sink.c: latency = 648 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.878| 0.000) [alsa-sink] sink-input.c: Have to rewind 10608 bytes on render memblockq. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.878| 0.000) [alsa-sink] source.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.878| 0.000) [alsa-sink] sink.c: Volume change to 32904 at 10200287313 was written 12 usec late Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.889| 0.011) [pulseaudio] protocol-native.c: Client gnome-control-center changes volume of sink alsa_output.pci-0000_00_1b.0.analog-surround-51. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 52% 1: 62% 2: 48% 3: 57% 4: 62% 5: 25% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -16.95 dB 1: -12.55 dB 2: -18.89 dB 3: -14.49 dB 4: -12.55 dB 5: -35.87 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 53% 1: 63% 2: 49% 3: 57% 4: 63% 5: 26% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -16.50 dB 1: -12.20 dB 2: -18.75 dB 3: -14.45 dB 4: -12.00 dB 5: -35.25 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 98% 1: 99% 2: 99% 3: 100% 4: 98% 5: 98% (accurate-enough=no) Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0.45 dB 1: -0.35 dB 2: -0.14 dB 3: -0.04 dB 4: -0.55 dB 5: -0.62 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] sink.c: Volume going up to 33945 at 10200324753 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 65520 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] alsa-sink.c: Limited to 13496 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] alsa-sink.c: before: 1124 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] alsa-sink.c: after: 1124 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] alsa-sink.c: Rewound 13488 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] sink.c: latency = 286 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.pci-0000_00_1b.0.analog-surround-51:analog-output. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] sink-input.c: Have to rewind 13488 bytes on render memblockq. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] source.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.890| 0.000) [alsa-sink] sink.c: Volume change to 33945 at 10200299280 was written 1 usec late Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.004) [pulseaudio] protocol-native.c: Client gnome-control-center changes volume of sink alsa_output.pci-0000_00_1b.0.analog-surround-51. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 50% 1: 59% 2: 54% 3: 64% 4: 59% 5: 25% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -18.25 dB 1: -13.85 dB 2: -16.23 dB 3: -11.83 dB 4: -13.85 dB 5: -35.87 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 50% 1: 59% 2: 54% 3: 64% 4: 60% 5: 26% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -18.20 dB 1: -13.70 dB 2: -15.95 dB 3: -11.45 dB 4: -13.50 dB 5: -35.25 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 99% 2: 99% 3: 99% 4: 99% 5: 98% (accurate-enough=no) Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0.05 dB 1: -0.15 dB 2: -0.28 dB 3: -0.38 dB 4: -0.35 dB 5: -0.62 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [alsa-sink] sink.c: Volume going up to 34180 at 10200324752 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 65520 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [alsa-sink] alsa-sink.c: Limited to 10616 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [alsa-sink] alsa-sink.c: before: 884 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [alsa-sink] alsa-sink.c: after: 884 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.pci-0000_00_1b.0.analog-surround-51:analog-output. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [alsa-sink] alsa-sink.c: Rewound 10608 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [alsa-sink] sink.c: latency = 459 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [alsa-sink] sink-input.c: Have to rewind 10608 bytes on render memblockq. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.895| 0.000) [alsa-sink] source.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.896| 0.000) [alsa-sink] sink.c: Volume change to 34180 at 10200304723 was written 12 usec late Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.009) [pulseaudio] protocol-native.c: Client gnome-control-center changes volume of sink alsa_output.pci-0000_00_1b.0.analog-surround-51. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 41% 1: 49% 2: 54% 3: 64% 4: 49% 5: 25% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -23.21 dB 1: -18.81 dB 2: -16.23 dB 3: -11.83 dB 4: -18.81 dB 5: -35.87 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 42% 1: 49% 2: 54% 3: 65% 4: 49% 5: 26% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -22.90 dB 1: -18.75 dB 2: -16.15 dB 3: -11.25 dB 4: -18.75 dB 5: -35.25 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 99% 1: 100% 2: 100% 3: 98% 4: 100% 5: 98% (accurate-enough=no) Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0.31 dB 1: -0.06 dB 2: -0.08 dB 3: -0.58 dB 4: -0.06 dB 5: -0.62 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [alsa-sink] sink.c: Volume going down to 30967 at 10200340530 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 65520 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [alsa-sink] alsa-sink.c: Limited to 13016 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [alsa-sink] alsa-sink.c: before: 1084 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [alsa-sink] alsa-sink.c: after: 1084 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [alsa-sink] alsa-sink.c: Rewound 13008 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.pci-0000_00_1b.0.analog-surround-51:analog-output. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [alsa-sink] sink.c: latency = 700 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [alsa-sink] sink-input.c: Have to rewind 13008 bytes on render memblockq. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.906| 0.000) [alsa-sink] source.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.907| 0.000) [alsa-sink] sink.c: Volume change to 30967 at 10200315996 was written 2 usec late Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.907| 0.000) [alsa-sink] alsa-sink.c: Written HW volume did not match with the request: 0: 42% 1: 49% 2: 54% 3: 65% 4: 49% 5: 26% (request) != 0: 41% 1: 49% 2: 53% 3: 65% 4: 49% 5: 26% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.907| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -22.90 dB 1: -18.75 dB 2: -16.15 dB 3: -11.25 dB 4: -18.75 dB 5: -35.25 dB (request) != 0: -23.25 dB 1: -18.75 dB 2: -16.50 dB 3: -11.25 dB 4: -18.75 dB 5: -35.25 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.007) [pulseaudio] protocol-native.c: Client gnome-control-center changes volume of sink alsa_output.pci-0000_00_1b.0.analog-surround-51. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 30% 1: 36% 2: 54% 3: 64% 4: 36% 5: 25% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -31.03 dB 1: -26.63 dB 2: -16.23 dB 3: -11.83 dB 4: -26.63 dB 5: -35.87 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 30% 1: 36% 2: 54% 3: 64% 4: 37% 5: 26% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -30.95 dB 1: -26.45 dB 2: -15.95 dB 3: -11.45 dB 4: -26.25 dB 5: -35.25 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 99% 2: 99% 3: 99% 4: 99% 5: 98% (accurate-enough=no) Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0.08 dB 1: -0.18 dB 2: -0.28 dB 3: -0.38 dB 4: -0.38 dB 5: -0.62 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] sink.c: Volume going down to 27062 at 10200341448 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 65520 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: Limited to 8984 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: before: 748 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: after: 748 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: Rewound 8976 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] sink.c: latency = 372 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] sink-input.c: Have to rewind 8976 bytes on render memblockq. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] source.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.pci-0000_00_1b.0.analog-surround-51:analog-output. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [pulseaudio] protocol-native.c: Client gnome-control-center changes volume of sink alsa_output.pci-0000_00_1b.0.analog-surround-51. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 30% 1: 36% 2: 54% 3: 64% 4: 36% 5: 25% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -31.04 dB 1: -26.64 dB 2: -16.23 dB 3: -11.83 dB 4: -26.64 dB 5: -35.87 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 30% 1: 36% 2: 54% 3: 64% 4: 37% 5: 26% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -30.95 dB 1: -26.45 dB 2: -15.95 dB 3: -11.45 dB 4: -26.25 dB 5: -35.25 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 99% 2: 99% 3: 99% 4: 99% 5: 98% (accurate-enough=no) Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0.08 dB 1: -0.19 dB 2: -0.28 dB 3: -0.38 dB 4: -0.39 dB 5: -0.62 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] sink.c: Volume going down to 27062 at 10200349961 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.915| 0.000) [alsa-sink] sink.c: Volume change to 27062 at 10200324487 was written 155 usec late Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.916| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 65520 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.916| 0.000) [alsa-sink] alsa-sink.c: Limited to 12860 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.916| 0.000) [alsa-sink] alsa-sink.c: before: 1071 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.916| 0.000) [alsa-sink] alsa-sink.c: after: 1071 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.916| 0.000) [alsa-sink] alsa-sink.c: Rewound 12852 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.916| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.916| 0.000) [alsa-sink] sink.c: latency = 782 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.916| 0.000) [alsa-sink] sink-input.c: Have to rewind 12852 bytes on render memblockq. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.916| 0.000) [alsa-sink] source.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.916| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.pci-0000_00_1b.0.analog-surround-51:analog-output. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.917| 0.000) [alsa-sink] sink.c: Volume change to 27062 at 10200325702 was written 0 usec late Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.919| 0.002) [pulseaudio] protocol-native.c: Client gnome-control-center changes volume of sink alsa_output.pci-0000_00_1b.0.analog-surround-51. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.919| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 20% 1: 23% 2: 54% 3: 64% 4: 23% 5: 25% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.919| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -42.26 dB 1: -37.86 dB 2: -16.23 dB 3: -11.83 dB 4: -37.86 dB 5: -35.87 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.919| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 20% 1: 24% 2: 54% 3: 64% 4: 24% 5: 26% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.919| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -42.20 dB 1: -37.70 dB 2: -15.95 dB 3: -11.45 dB 4: -37.50 dB 5: -35.25 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.919| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 99% 2: 99% 3: 99% 4: 99% 5: 98% (accurate-enough=no) Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.919| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0.06 dB 1: -0.16 dB 2: -0.28 dB 3: -0.38 dB 4: -0.36 dB 5: -0.62 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.919| 0.000) [alsa-sink] sink.c: Volume going down to 23108 at 10200351162 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.919| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 65520 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.919| 0.000) [alsa-sink] alsa-sink.c: Limited to 11624 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.919| 0.000) [alsa-sink] alsa-sink.c: before: 968 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.919| 0.000) [alsa-sink] alsa-sink.c: after: 968 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.919| 0.000) [alsa-sink] alsa-sink.c: Rewound 11616 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.919| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.pci-0000_00_1b.0.analog-surround-51:analog-output. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.919| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.919| 0.000) [alsa-sink] sink.c: latency = 575 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.920| 0.000) [alsa-sink] sink-input.c: Have to rewind 11616 bytes on render memblockq. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.920| 0.000) [alsa-sink] source.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.920| 0.000) [alsa-sink] sink.c: Volume change to 23108 at 10200329225 was written 12 usec late Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.924| 0.004) [pulseaudio] protocol-native.c: Client gnome-control-center changes volume of sink alsa_output.pci-0000_00_1b.0.analog-surround-51. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.924| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 9% 1: 11% 2: 54% 3: 64% 4: 11% 5: 25% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.924| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -62.41 dB 1: -58.01 dB 2: -16.23 dB 3: -11.83 dB 4: -58.01 dB 5: -35.87 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.924| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 9% 1: 11% 2: 55% 3: 64% 4: 11% 5: 26% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.924| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -62.25 dB 1: -57.95 dB 2: -15.75 dB 3: -11.45 dB 4: -57.75 dB 5: -35.25 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.924| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 99% 1: 100% 2: 98% 3: 99% 4: 99% 5: 98% (accurate-enough=no) Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.925| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0.16 dB 1: -0.06 dB 2: -0.48 dB 3: -0.38 dB 4: -0.26 dB 5: -0.62 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.925| 0.000) [alsa-sink] sink.c: Volume going down to 19204 at 10200354697 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.925| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 65520 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.925| 0.000) [alsa-sink] alsa-sink.c: Limited to 11024 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.925| 0.000) [alsa-sink] alsa-sink.c: before: 918 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.925| 0.000) [alsa-sink] alsa-sink.c: after: 918 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.925| 0.000) [alsa-sink] alsa-sink.c: Rewound 11016 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.925| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.925| 0.000) [alsa-sink] sink.c: latency = 104 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.925| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.pci-0000_00_1b.0.analog-surround-51:analog-output. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.925| 0.000) [alsa-sink] sink-input.c: Have to rewind 11016 bytes on render memblockq. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.925| 0.000) [alsa-sink] source.c: Processing rewind... Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.925| 0.000) [alsa-sink] sink.c: Volume change to 19204 at 10200333891 was written 86 usec late Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.935| 0.009) [pulseaudio] protocol-native.c: Client gnome-control-center changes volume of sink alsa_output.pci-0000_00_1b.0.analog-surround-51. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.935| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 3% 1: 3% 2: 54% 3: 64% 4: 3% 5: 25% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.935| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -95.80 dB 1: -91.40 dB 2: -16.23 dB 3: -11.83 dB 4: -91.40 dB 5: -35.87 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.935| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 5% 1: 5% 2: 54% 3: 64% 4: 5% 5: 26% Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.935| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -76.90 dB 1: -76.90 dB 2: -16.15 dB 3: -11.65 dB 4: -76.50 dB 5: -35.25 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.935| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 48% 1: 57% 2: 100% 3: 99% 4: 56% 5: 98% (accurate-enough=no) Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.935| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -18.90 dB 1: -14.50 dB 2: -0.08 dB 3: -0.18 dB 4: -14.90 dB 5: -0.62 dB Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.935| 0.000) [alsa-sink] sink.c: Volume going down to 17407 at 10200369525 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.935| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 65520 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.935| 0.000) [alsa-sink] alsa-sink.c: Limited to 13016 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.935| 0.000) [alsa-sink] alsa-sink.c: before: 1084 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.935| 0.000) [alsa-sink] alsa-sink.c: after: 1084 Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.935| 0.000) [alsa-sink] alsa-sink.c: Rewound 13008 bytes. Oct 16 12:20:31 auxiliary pulseaudio[3357]: ( 57.935| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 16 12:20:37 auxiliary pulseaudio[3357]: ( 64.590| 1.472) [pulseaudio] client.c: Created 146 "UNIX socket client"