Oct 27 20:56:03 caeser pulseaudio[2545]: [pulseaudio] cli.c: CLI got EOF from user. Oct 27 20:56:03 caeser pulseaudio[2545]: [pulseaudio] client.c: Freed 17 "UNIX socket client" Oct 27 20:56:03 caeser pulseaudio[2545]: [pulseaudio] client.c: Created 18 "UNIX socket client" Oct 27 20:56:03 caeser pulseaudio[2545]: ( 0.000| 0.000) [pulseaudio] cli.c: CLI got EOF from user. Oct 27 20:56:03 caeser pulseaudio[2545]: ( 0.000| 0.000) [pulseaudio] client.c: Freed 18 "UNIX socket client" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.094| 55.094) [pulseaudio] client.c: Created 19 "Native client (UNIX socket client)" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.103| 0.009) [pulseaudio] client.c: Freed 19 "Native client (UNIX socket client)" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.103| 0.000) [pulseaudio] protocol-native.c: Connection died. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.117| 0.013) [pulseaudio] client.c: Created 20 "Native client (UNIX socket client)" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.117| 0.000) [pulseaudio] protocol-native.c: Protocol version: remote 24, local 24 Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.117| 0.000) [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1 Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.117| 0.000) [pulseaudio] protocol-native.c: SHM possible: yes Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.117| 0.000) [pulseaudio] protocol-native.c: Negotiated SHM: yes Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.118| 0.000) [pulseaudio] module-augment-properties.c: Looking for .desktop file for firefox Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.118| 0.000) [pulseaudio] module-augment-properties.c: Found /usr/share/applications/firefox.desktop. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.118| 0.000) [pulseaudio] module-stream-restore.c: Restoring device for stream sink-input-by-application-name:ALSA plug-in [firefox]. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.118| 0.000) [pulseaudio] module-intended-roles.c: Not setting device for stream ALSA Playback, because already set. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.118| 0.000) [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-application-name:ALSA plug-in [firefox]. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.118| 0.000) [pulseaudio] sink.c: Suspend cause of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo is 0x0000, resuming Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.121| 0.003) [pulseaudio] reserve-wrap.c: Successfully acquired reservation lock on device 'Audio2' Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.122| 0.000) [alsa-sink] alsa-sink.c: Trying resume... Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.122| 0.000) [alsa-sink] alsa-util.c: cannot disable ALSA period wakeups Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.122| 0.000) [alsa-sink] alsa-util.c: Maximum hw buffer size is 5944 ms Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.126| 0.003) [alsa-sink] alsa-util.c: Set buffer size first (to 88200 samples), period size second (to 44100 samples). Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.002) [alsa-sink] alsa-util.c: ALSA period wakeups were not disabled Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=0 Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=87319 Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [alsa-sink] alsa-sink.c: Resumed successfully... Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes idle, timeout in 5 seconds. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [alsa-sink] alsa-sink.c: Starting playback. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [alsa-sink] ratelimit.c: 8 events suppressed Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes busy. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] sink-input.c: Created input 0 "ALSA Playback" on alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo with sample spec s16le 2ch 44100Hz and channel map front-left,front-right Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] sink-input.c: media.name = "ALSA Playback" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] sink-input.c: application.name = "ALSA plug-in [firefox]" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] sink-input.c: native-protocol.version = "24" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] sink-input.c: application.process.id = "3041" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] sink-input.c: application.process.user = "raphael" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] sink-input.c: application.process.host = "caeser" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] sink-input.c: application.process.binary = "firefox" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] sink-input.c: window.x11.display = ":0" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] sink-input.c: application.language = "de_DE.UTF-8" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] sink-input.c: application.process.machine_id = "de3399e1c0401d2cf76639074bdc22c5" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] sink-input.c: application.process.session_id = "de3399e1c0401d2cf76639074bdc22c5-1319733621.487632-440420476" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] sink-input.c: application.icon_name = "firefox" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-application-name:ALSA plug-in [firefox]" Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] protocol-native.c: Requested tlength=500,00 ms, minreq=124,99 ms Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.129| 0.000) [pulseaudio] protocol-native.c: Early requests mode enabled, configuring sink latency to minreq. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=88200, base=4, prebuf=66152, minreq=22048 maxrewind=0 Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=88200, base=4, prebuf=66152, minreq=22048 maxrewind=0 Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [pulseaudio] protocol-native.c: Final latency 624,99 ms = 250,02 ms + 2*124,99 ms + 124,99 ms Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: Latency set to 124,99ms Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=330756 Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=87319 Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: Requesting rewind due to latency change. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 48% 1: 48% Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -19,32 dB 1: -19,32 dB Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 48% 1: 48% Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -19,13 dB 1: -19,13 dB Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 99% 1: 99% (accurate-enough=yes) Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,19 dB 1: -0,19 dB Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: Limited to 352544 bytes. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: before: 88136 Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: after: 88136 Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] alsa-sink.c: Rewound 352544 bytes. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] sink.c: latency = 301 Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] sink-input.c: Have to rewind 352544 bytes on render memblockq. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.130| 0.000) [pulseaudio] reserve-wrap.c: Device lock status of reserve-monitor-wrapper@Audio2 changed: not busy Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.380| 0.249) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.380| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.380| 0.000) [alsa-sink] alsa-sink.c: Limited to 14732 bytes. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.380| 0.000) [alsa-sink] alsa-sink.c: before: 3683 Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.380| 0.000) [alsa-sink] alsa-sink.c: after: 3683 Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.380| 0.000) [alsa-sink] alsa-sink.c: Rewound 14732 bytes. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.380| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.380| 0.000) [alsa-sink] sink.c: latency = 0 Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.380| 0.000) [alsa-sink] sink-input.c: Have to rewind 14732 bytes on render memblockq. Oct 27 20:56:58 caeser pulseaudio[2545]: ( 55.380| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.605| 4.225) [pulseaudio] client.c: Created 21 "Native client (UNIX socket client)" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.713| 0.107) [pulseaudio] protocol-native.c: Protocol version: remote 24, local 24 Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.713| 0.000) [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1 Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.713| 0.000) [pulseaudio] protocol-native.c: SHM possible: yes Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.713| 0.000) [pulseaudio] protocol-native.c: Negotiated SHM: yes Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.714| 0.000) [pulseaudio] module-augment-properties.c: Looking for .desktop file for gnome-control-center Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.796| 0.082) [pulseaudio] module-stream-restore.c: Not restoring device for stream source-output-by-application-id:org.gnome.VolumeControl, because already set Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.796| 0.000) [pulseaudio] module-intended-roles.c: Not setting device for stream Peak detect, because already set. Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.796| 0.000) [pulseaudio] module-stream-restore.c: Restoring mute state for source output source-output-by-application-id:org.gnome.VolumeControl. Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.796| 0.000) [pulseaudio] source.c: Suspend cause of source alsa_input.pci-0000_00_1b.0.analog-stereo is 0x0000, resuming Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.802| 0.005) [pulseaudio] reserve-wrap.c: Successfully acquired reservation lock on device 'Audio0' Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.802| 0.000) [alsa-source] alsa-source.c: Trying resume... Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.802| 0.000) [alsa-source] alsa-util.c: Trying to disable ALSA period wakeups, using timers only Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.802| 0.000) [alsa-source] alsa-util.c: Maximum hw buffer size is 23777 ms Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.802| 0.000) [alsa-source] alsa-util.c: Set buffer size first (to 88192 samples), period size second (to 44096 samples). Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [alsa-source] alsa-util.c: ALSA period wakeups disabled Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [alsa-source] alsa-source.c: hwbuf_unused=0 Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [alsa-source] alsa-source.c: setting avail_min=87751 Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [alsa-source] alsa-source.c: Resumed successfully... Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [alsa-source] alsa-source.c: Starting capture. Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 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 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] module-suspend-on-idle.c: Source alsa_input.pci-0000_00_1b.0.analog-stereo becomes busy. Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] resampler.c: Channel matrix: Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] resampler.c: I00 I01 Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] resampler.c: +------------ Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] resampler.c: O00 | 1,000 1,000 Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] remap.c: Using generic matrix remapping Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] resampler.c: Using resampler 'peaks' Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] resampler.c: Using float32le as working format. Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: Created output 1 "Peak detect" on alsa_input.pci-0000_00_1b.0.analog-stereo with sample spec float32le 1ch 25Hz and channel map mono Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: application.id = "org.gnome.VolumeControl" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: media.name = "Peak detect" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: application.name = "GNOME Volume Control Dialog" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: native-protocol.peer = "UNIX socket client" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: native-protocol.version = "24" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: application.icon_name = "multimedia-volume-control" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: application.version = "3.2.1" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: application.process.id = "25915" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: application.process.user = "raphael" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: application.process.host = "caeser" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: application.process.binary = "gnome-control-center" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: application.language = "de_DE.UTF-8" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: window.x11.display = ":0" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: application.process.machine_id = "de3399e1c0401d2cf76639074bdc22c5" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: application.process.session_id = "de3399e1c0401d2cf76639074bdc22c5-1319733621.487632-440420476" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.803| 0.000) [pulseaudio] source-output.c: module-stream-restore.id = "source-output-by-application-id:org.gnome.VolumeControl" Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=0, base=4, prebuf=1, minreq=0 maxrewind=0 Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=4194304, base=4, prebuf=4, minreq=4 maxrewind=0 Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [pulseaudio] protocol-native.c: Final latency 60,00 ms = 40,00 ms + 20,00 ms Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [alsa-source] alsa-source.c: latency set to 20,00ms Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [alsa-source] alsa-source.c: hwbuf_unused=349240 Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [alsa-source] alsa-source.c: setting avail_min=442 Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [alsa-source] alsa-source.c: latency set to 20,00ms Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [alsa-source] alsa-source.c: hwbuf_unused=349240 Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [alsa-source] alsa-source.c: setting avail_min=442 Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [alsa-source] alsa-source.c: Requested volume: 0: 84% 1: 84% Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [alsa-source] alsa-source.c: in dB: 0: -4,50 dB 1: -4,50 dB Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [alsa-source] alsa-source.c: Got hardware volume: 0: 84% 1: 84% Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [alsa-source] alsa-source.c: in dB: 0: -4,50 dB 1: -4,50 dB Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [alsa-source] alsa-source.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [alsa-source] alsa-source.c: in dB: 0: 0,00 dB 1: 0,00 dB Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [alsa-source] source.c: Volume not changing Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Oct 27 20:57:02 caeser pulseaudio[2545]: ( 59.804| 0.000) [pulseaudio] reserve-wrap.c: Device lock status of reserve-monitor-wrapper@Audio0 changed: not busy Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.734| 3.930) [pulseaudio] protocol-native.c: Client gnome-settings-daemon changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.734| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 42% 1: 42% Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -22,83 dB 1: -22,83 dB Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 42% 1: 42% Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -22,69 dB 1: -22,69 dB Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 99% 1: 99% (accurate-enough=yes) Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,14 dB 1: -0,14 dB Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] sink.c: Volume going down to 27292 at 8245289957 Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] sink.c: Next volume change in 92699 usec Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] sink.c: Next volume change in 92683 usec Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] sink.c: Next volume change in 92489 usec Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] sink.c: Next volume change in 92468 usec Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] sink.c: Next volume change in 92447 usec Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] sink.c: Next volume change in 92434 usec Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] sink.c: Next volume change in 92404 usec Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] sink.c: Next volume change in 92388 usec Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] sink.c: Next volume change in 92372 usec Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] sink.c: Next volume change in 92359 usec Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.735| 0.000) [alsa-sink] sink.c: Next volume change in 92316 usec Oct 27 20:57:06 caeser pulseaudio[2545]: ( 63.827| 0.092) [alsa-sink] sink.c: Volume change to 27292 at 8245289957 was written 14 usec late Oct 27 20:57:07 caeser pulseaudio[2545]: ( 64.009| 0.182) [pulseaudio] protocol-native.c: Client gnome-settings-daemon changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:07 caeser pulseaudio[2545]: ( 64.010| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 36% 1: 36% Oct 27 20:57:07 caeser pulseaudio[2545]: ( 64.010| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -26,88 dB 1: -26,88 dB Oct 27 20:57:07 caeser pulseaudio[2545]: ( 64.010| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 36% 1: 36% Oct 27 20:57:07 caeser pulseaudio[2545]: ( 64.010| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -26,82 dB 1: -26,82 dB Oct 27 20:57:07 caeser pulseaudio[2545]: ( 64.010| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:07 caeser pulseaudio[2545]: ( 64.010| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,06 dB 1: -0,06 dB Oct 27 20:57:07 caeser pulseaudio[2545]: ( 64.010| 0.000) [alsa-sink] sink.c: Volume going down to 23360 at 8245594078 Oct 27 20:57:07 caeser pulseaudio[2545]: ( 64.010| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:07 caeser pulseaudio[2545]: ( 64.131| 0.121) [alsa-sink] sink.c: Volume change to 23360 at 8245594078 was written 22 usec late Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.939| 2.807) [alsa-sink] protocol-native.c: Underrun on 'ALSA Playback', 0 bytes in queue. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.944| 0.005) [alsa-sink] alsa-sink.c: hwbuf_unused=0 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.944| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=87319 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.944| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 36% 1: 36% Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.944| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -26,88 dB 1: -26,88 dB Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.944| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 36% 1: 36% Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.944| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -26,82 dB 1: -26,82 dB Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.944| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.944| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,06 dB 1: -0,06 dB Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.944| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.944| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.944| 0.000) [alsa-sink] alsa-sink.c: Limited to 20376 bytes. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.944| 0.000) [alsa-sink] alsa-sink.c: before: 5094 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.944| 0.000) [alsa-sink] alsa-sink.c: after: 5094 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.944| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes idle, timeout in 5 seconds. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.944| 0.000) [alsa-sink] alsa-sink.c: Rewound 20376 bytes. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.944| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.945| 0.000) [alsa-sink] sink.c: latency = 7042 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.945| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.945| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes idle, timeout in 5 seconds. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.945| 0.000) [pulseaudio] sink-input.c: Freeing input 0 "ALSA Playback" Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.001) [pulseaudio] module-stream-restore.c: Restoring device for stream sink-input-by-application-name:ALSA plug-in [firefox]. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] module-intended-roles.c: Not setting device for stream ALSA Playback, because already set. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-application-name:ALSA plug-in [firefox]. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes busy. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] sink-input.c: Created input 1 "ALSA Playback" on alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo with sample spec s16le 2ch 44100Hz and channel map front-left,front-right Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] sink-input.c: media.name = "ALSA Playback" Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] sink-input.c: application.name = "ALSA plug-in [firefox]" Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] sink-input.c: native-protocol.version = "24" Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] sink-input.c: application.process.id = "3041" Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] sink-input.c: application.process.user = "raphael" Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] sink-input.c: application.process.host = "caeser" Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] sink-input.c: application.process.binary = "firefox" Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] sink-input.c: window.x11.display = ":0" Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] sink-input.c: application.language = "de_DE.UTF-8" Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] sink-input.c: application.process.machine_id = "de3399e1c0401d2cf76639074bdc22c5" Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] sink-input.c: application.process.session_id = "de3399e1c0401d2cf76639074bdc22c5-1319733621.487632-440420476" Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] sink-input.c: application.icon_name = "firefox" Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-application-name:ALSA plug-in [firefox]" Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] protocol-native.c: Requested tlength=500,00 ms, minreq=124,99 ms Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] protocol-native.c: Early requests mode enabled, configuring sink latency to minreq. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=88200, base=4, prebuf=66152, minreq=22048 maxrewind=0 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=88200, base=4, prebuf=66152, minreq=22048 maxrewind=0 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.947| 0.000) [pulseaudio] protocol-native.c: Final latency 624,99 ms = 250,02 ms + 2*124,99 ms + 124,99 ms Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] alsa-sink.c: Latency set to 124,99ms Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=330756 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=87319 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] alsa-sink.c: Requesting rewind due to latency change. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 36% 1: 36% Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -26,88 dB 1: -26,88 dB Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 36% 1: 36% Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -26,82 dB 1: -26,82 dB Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,06 dB 1: -0,06 dB Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] alsa-sink.c: Limited to 351132 bytes. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] alsa-sink.c: before: 87783 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] alsa-sink.c: after: 87783 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] alsa-sink.c: Rewound 351132 bytes. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] sink.c: latency = 11639 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] sink-input.c: Have to rewind 351132 bytes on render memblockq. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 66.948| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:10 caeser pulseaudio[2545]: ( 67.927| 0.979) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 67.927| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 67.927| 0.000) [alsa-sink] alsa-sink.c: Limited to 20380 bytes. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 67.927| 0.000) [alsa-sink] alsa-sink.c: before: 5095 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 67.927| 0.000) [alsa-sink] alsa-sink.c: after: 5095 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 67.927| 0.000) [alsa-sink] alsa-sink.c: Rewound 20380 bytes. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 67.927| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:10 caeser pulseaudio[2545]: ( 67.927| 0.000) [alsa-sink] sink.c: latency = 7640 Oct 27 20:57:10 caeser pulseaudio[2545]: ( 67.927| 0.000) [alsa-sink] sink-input.c: Have to rewind 20380 bytes on render memblockq. Oct 27 20:57:10 caeser pulseaudio[2545]: ( 67.927| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:11 caeser pulseaudio[2545]: ( 68.339| 0.411) [alsa-sink] protocol-native.c: Underrun on 'ALSA Playback', 0 bytes in queue. Oct 27 20:57:11 caeser pulseaudio[2545]: ( 68.340| 0.000) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 27 20:57:11 caeser pulseaudio[2545]: ( 68.340| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 13252 bytes. Oct 27 20:57:11 caeser pulseaudio[2545]: ( 68.340| 0.000) [alsa-sink] alsa-sink.c: Limited to 13252 bytes. Oct 27 20:57:11 caeser pulseaudio[2545]: ( 68.340| 0.000) [alsa-sink] alsa-sink.c: before: 3313 Oct 27 20:57:11 caeser pulseaudio[2545]: ( 68.340| 0.000) [alsa-sink] alsa-sink.c: after: 3313 Oct 27 20:57:11 caeser pulseaudio[2545]: ( 68.340| 0.000) [alsa-sink] alsa-sink.c: Rewound 13252 bytes. Oct 27 20:57:11 caeser pulseaudio[2545]: ( 68.340| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:11 caeser pulseaudio[2545]: ( 68.340| 0.000) [alsa-sink] sink.c: latency = 59521 Oct 27 20:57:11 caeser pulseaudio[2545]: ( 68.340| 0.000) [alsa-sink] sink-input.c: Have to rewind 13252 bytes on render memblockq. Oct 27 20:57:11 caeser pulseaudio[2545]: ( 68.340| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:11 caeser pulseaudio[2545]: ( 68.340| 0.000) [alsa-sink] protocol-native.c: Underrun on 'ALSA Playback', 0 bytes in queue. Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.614| 2.274) [pulseaudio] protocol-native.c: Client gnome-settings-daemon changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.614| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 42% 1: 42% Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.614| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -22,83 dB 1: -22,83 dB Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.614| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 42% 1: 42% Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.614| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -22,69 dB 1: -22,69 dB Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.614| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 99% 1: 99% (accurate-enough=yes) Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.614| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,14 dB 1: -0,14 dB Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.614| 0.000) [alsa-sink] sink.c: Volume going up to 27292 at 8252219138 Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.614| 0.000) [alsa-sink] ratelimit.c: 274 events suppressed Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.615| 0.000) [alsa-sink] sink.c: Next volume change in 142011 usec Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.615| 0.000) [alsa-sink] sink.c: Next volume change in 141986 usec Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.615| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.615| 0.000) [alsa-sink] sink.c: Next volume change in 141729 usec Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.615| 0.000) [alsa-sink] sink.c: Next volume change in 141698 usec Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.615| 0.000) [alsa-sink] sink.c: Next volume change in 141678 usec Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.615| 0.000) [alsa-sink] sink.c: Next volume change in 141665 usec Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.615| 0.000) [alsa-sink] sink.c: Next volume change in 141637 usec Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.615| 0.000) [alsa-sink] sink.c: Next volume change in 141621 usec Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.615| 0.000) [alsa-sink] sink.c: Next volume change in 141605 usec Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.615| 0.000) [alsa-sink] sink.c: Next volume change in 141592 usec Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.615| 0.000) [alsa-sink] sink.c: Next volume change in 141576 usec Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.757| 0.141) [alsa-sink] sink.c: Volume change to 27292 at 8252219138 was written 28 usec late Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.857| 0.100) [pulseaudio] protocol-native.c: Client gnome-settings-daemon changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.857| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 48% 1: 48% Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.857| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -19,32 dB 1: -19,32 dB Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.857| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 48% 1: 48% Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.857| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -19,13 dB 1: -19,13 dB Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.857| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 99% 1: 99% (accurate-enough=yes) Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.857| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,19 dB 1: -0,19 dB Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.857| 0.000) [alsa-sink] sink.c: Volume going up to 31224 at 8252426670 Oct 27 20:57:13 caeser pulseaudio[2545]: ( 70.857| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:14 caeser pulseaudio[2545]: ( 70.964| 0.106) [alsa-sink] sink.c: Volume change to 31224 at 8252426670 was written 20 usec late Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.113| 0.148) [pulseaudio] protocol-native.c: Client gnome-settings-daemon changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.113| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 54% 1: 54% Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.113| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -16,23 dB 1: -16,23 dB Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.113| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 54% 1: 54% Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.113| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -16,13 dB 1: -16,13 dB Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.113| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.113| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,10 dB 1: -0,10 dB Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.113| 0.000) [alsa-sink] sink.c: Volume going up to 35156 at 8252674551 Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.113| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.212| 0.098) [alsa-sink] sink.c: Volume change to 35156 at 8252674551 was written 22 usec late Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.305| 0.092) [pulseaudio] protocol-native.c: Client gnome-settings-daemon changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.305| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 60% 1: 60% Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.305| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -13,47 dB 1: -13,47 dB Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.305| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 60% 1: 60% Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.305| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -13,32 dB 1: -13,32 dB Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.305| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 99% 1: 99% (accurate-enough=yes) Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.305| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,15 dB 1: -0,15 dB Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.305| 0.000) [alsa-sink] sink.c: Volume going up to 39088 at 8252882671 Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.305| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.420| 0.114) [alsa-sink] sink.c: Volume change to 39088 at 8252882671 was written 12 usec late Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.497| 0.076) [pulseaudio] protocol-native.c: Client gnome-settings-daemon changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.497| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 66% 1: 66% Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.497| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -10,97 dB 1: -10,97 dB Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.497| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 66% 1: 66% Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.497| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -10,88 dB 1: -10,88 dB Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.497| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.497| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,09 dB 1: -0,09 dB Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.497| 0.000) [alsa-sink] sink.c: Volume going up to 43020 at 8253090820 Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.497| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.628| 0.131) [alsa-sink] sink.c: Volume change to 43020 at 8253090820 was written 25 usec late Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.693| 0.065) [pulseaudio] protocol-native.c: Client gnome-settings-daemon changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.694| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 72% 1: 72% Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.694| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -8,69 dB 1: -8,69 dB Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.694| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 72% 1: 72% Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.694| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -8,63 dB 1: -8,63 dB Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.694| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.694| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,06 dB 1: -0,06 dB Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.694| 0.000) [alsa-sink] sink.c: Volume going up to 46952 at 8253298988 Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.694| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.836| 0.142) [alsa-sink] sink.c: Volume change to 46952 at 8253298988 was written 22 usec late Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.884| 0.047) [pulseaudio] protocol-native.c: Client gnome-settings-daemon changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.884| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 78% 1: 78% Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.884| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -6,59 dB 1: -6,59 dB Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.884| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 78% 1: 78% Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.884| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -6,57 dB 1: -6,57 dB Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.884| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.884| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,02 dB 1: -0,02 dB Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.884| 0.000) [alsa-sink] sink.c: Volume going up to 50884 at 8253490896 Oct 27 20:57:14 caeser pulseaudio[2545]: ( 71.884| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.028| 0.144) [alsa-sink] sink.c: Volume change to 50884 at 8253490896 was written 25 usec late Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.105| 0.076) [pulseaudio] protocol-native.c: Client gnome-settings-daemon changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.105| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 84% 1: 84% Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.105| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -4,65 dB 1: -4,65 dB Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.105| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 84% 1: 84% Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.105| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -4,50 dB 1: -4,50 dB Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.105| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 99% 1: 99% (accurate-enough=yes) Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.105| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,15 dB 1: -0,15 dB Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.105| 0.000) [alsa-sink] sink.c: Volume going up to 54816 at 8253698501 Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.105| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.236| 0.130) [alsa-sink] sink.c: Volume change to 54816 at 8253698501 was written 22 usec late Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.267| 0.031) [pulseaudio] protocol-native.c: Client gnome-settings-daemon changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.267| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 90% 1: 90% Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.267| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,85 dB 1: -2,85 dB Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.268| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 90% 1: 90% Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.268| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,82 dB 1: -2,82 dB Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.268| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.268| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,03 dB 1: -0,03 dB Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.268| 0.000) [alsa-sink] sink.c: Volume going up to 58748 at 8253842471 Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.268| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.380| 0.112) [alsa-sink] sink.c: Volume change to 58748 at 8253842471 was written 31 usec late Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.707| 0.326) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.707| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.707| 0.000) [alsa-sink] alsa-sink.c: Limited to 17552 bytes. Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.707| 0.000) [alsa-sink] alsa-sink.c: before: 4388 Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.707| 0.000) [alsa-sink] alsa-sink.c: after: 4388 Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.707| 0.000) [alsa-sink] alsa-sink.c: Rewound 17552 bytes. Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.707| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.707| 0.000) [alsa-sink] sink.c: latency = 12920 Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.707| 0.000) [alsa-sink] sink-input.c: Have to rewind 17552 bytes on render memblockq. Oct 27 20:57:15 caeser pulseaudio[2545]: ( 72.707| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:16 caeser pulseaudio[2545]: ( 73.177| 0.470) [alsa-sink] protocol-native.c: Underrun on 'ALSA Playback', 0 bytes in queue. Oct 27 20:57:16 caeser pulseaudio[2545]: ( 73.735| 0.557) [pulseaudio] module-device-restore.c: Synced. Oct 27 20:57:17 caeser pulseaudio[2545]: ( 73.971| 0.236) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 27 20:57:17 caeser pulseaudio[2545]: ( 73.971| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 147100 bytes. Oct 27 20:57:17 caeser pulseaudio[2545]: ( 73.971| 0.000) [alsa-sink] alsa-sink.c: Limited to 21788 bytes. Oct 27 20:57:17 caeser pulseaudio[2545]: ( 73.971| 0.000) [alsa-sink] alsa-sink.c: before: 5447 Oct 27 20:57:17 caeser pulseaudio[2545]: ( 73.971| 0.000) [alsa-sink] alsa-sink.c: after: 5447 Oct 27 20:57:17 caeser pulseaudio[2545]: ( 73.971| 0.000) [alsa-sink] alsa-sink.c: Rewound 21788 bytes. Oct 27 20:57:17 caeser pulseaudio[2545]: ( 73.971| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:17 caeser pulseaudio[2545]: ( 73.971| 0.000) [alsa-sink] sink.c: latency = 13468 Oct 27 20:57:17 caeser pulseaudio[2545]: ( 73.971| 0.000) [alsa-sink] sink-input.c: Have to rewind 21788 bytes on render memblockq. Oct 27 20:57:17 caeser pulseaudio[2545]: ( 73.971| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:17 caeser pulseaudio[2545]: ( 74.530| 0.558) [alsa-sink] sink-input.c: Requesting rewind due to corking Oct 27 20:57:17 caeser pulseaudio[2545]: ( 74.530| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 110696 bytes. Oct 27 20:57:17 caeser pulseaudio[2545]: ( 74.530| 0.000) [alsa-sink] alsa-sink.c: Limited to 13324 bytes. Oct 27 20:57:17 caeser pulseaudio[2545]: ( 74.530| 0.000) [alsa-sink] alsa-sink.c: before: 3331 Oct 27 20:57:17 caeser pulseaudio[2545]: ( 74.530| 0.000) [alsa-sink] alsa-sink.c: after: 3331 Oct 27 20:57:17 caeser pulseaudio[2545]: ( 74.530| 0.000) [alsa-sink] alsa-sink.c: Rewound 13324 bytes. Oct 27 20:57:17 caeser pulseaudio[2545]: ( 74.530| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:17 caeser pulseaudio[2545]: ( 74.530| 0.000) [alsa-sink] sink.c: latency = 5016 Oct 27 20:57:17 caeser pulseaudio[2545]: ( 74.530| 0.000) [alsa-sink] sink-input.c: Have to rewind 13324 bytes on render memblockq. Oct 27 20:57:17 caeser pulseaudio[2545]: ( 74.530| 0.000) [alsa-sink] sink-input.c: Have to rewind 13324 bytes on implementor. Oct 27 20:57:17 caeser pulseaudio[2545]: ( 74.530| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:17 caeser pulseaudio[2545]: ( 74.530| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes idle, timeout in 5 seconds. Oct 27 20:57:22 caeser pulseaudio[2545]: ( 79.530| 5.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo idle for too long, suspending ... Oct 27 20:57:22 caeser pulseaudio[2545]: ( 79.530| 0.000) [pulseaudio] sink.c: Suspend cause of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo is 0x0004, suspending Oct 27 20:57:22 caeser pulseaudio[2545]: ( 79.539| 0.008) [alsa-sink] alsa-sink.c: Device suspended... Oct 27 20:57:22 caeser pulseaudio[2545]: ( 79.539| 0.000) [pulseaudio] reserve-wrap.c: Device lock status of reserve-monitor-wrapper@Audio2 changed: not busy Oct 27 20:57:22 caeser pulseaudio[2545]: ( 79.539| 0.000) [pulseaudio] module-udev-detect.c: /dev/snd/controlC2 is accessible: yes Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.532| 1.993) [pulseaudio] client.c: Created 22 "Native client (UNIX socket client)" Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.533| 0.000) [pulseaudio] protocol-native.c: Protocol version: remote 24, local 24 Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.533| 0.000) [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1 Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.533| 0.000) [pulseaudio] protocol-native.c: SHM possible: yes Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.533| 0.000) [pulseaudio] protocol-native.c: Negotiated SHM: yes Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.533| 0.000) [pulseaudio] module-augment-properties.c: Looking for .desktop file for firefox Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.533| 0.000) [pulseaudio] module-stream-restore.c: Restoring device for stream sink-input-by-application-name:ALSA plug-in [firefox]. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.533| 0.000) [pulseaudio] module-intended-roles.c: Not setting device for stream ALSA Playback, because already set. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.533| 0.000) [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-application-name:ALSA plug-in [firefox]. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.533| 0.000) [pulseaudio] sink.c: Suspend cause of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo is 0x0000, resuming Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.537| 0.003) [pulseaudio] reserve-wrap.c: Successfully acquired reservation lock on device 'Audio2' Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.537| 0.000) [alsa-sink] alsa-sink.c: Trying resume... Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.538| 0.000) [alsa-sink] alsa-util.c: cannot disable ALSA period wakeups Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.538| 0.000) [alsa-sink] alsa-util.c: Maximum hw buffer size is 5944 ms Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.542| 0.003) [alsa-sink] alsa-util.c: Set buffer size first (to 88200 samples), period size second (to 44100 samples). Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.002) [alsa-sink] alsa-util.c: ALSA period wakeups were not disabled Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [alsa-sink] alsa-sink.c: Latency set to 124,99ms Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=330756 Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=87319 Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [alsa-sink] alsa-sink.c: Resumed successfully... Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [alsa-sink] alsa-sink.c: Starting playback. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes idle, timeout in 5 seconds. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [alsa-sink] ratelimit.c: 281 events suppressed Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes busy. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] sink-input.c: Created input 2 "ALSA Playback" on alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo with sample spec s16le 2ch 44100Hz and channel map front-left,front-right Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] sink-input.c: media.name = "ALSA Playback" Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] sink-input.c: application.name = "ALSA plug-in [firefox]" Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] sink-input.c: native-protocol.version = "24" Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] sink-input.c: application.process.id = "3041" Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] sink-input.c: application.process.user = "raphael" Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] sink-input.c: application.process.host = "caeser" Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] sink-input.c: application.process.binary = "firefox" Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] sink-input.c: window.x11.display = ":0" Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] sink-input.c: application.language = "de_DE.UTF-8" Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] sink-input.c: application.process.machine_id = "de3399e1c0401d2cf76639074bdc22c5" Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] sink-input.c: application.process.session_id = "de3399e1c0401d2cf76639074bdc22c5-1319733621.487632-440420476" Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] sink-input.c: application.icon_name = "firefox" Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-application-name:ALSA plug-in [firefox]" Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] protocol-native.c: Requested tlength=500,00 ms, minreq=124,99 ms Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [pulseaudio] protocol-native.c: Early requests mode enabled, configuring sink latency to minreq. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.545| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=88200, base=4, prebuf=66152, minreq=22048 maxrewind=0 Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=88200, base=4, prebuf=66152, minreq=22048 maxrewind=0 Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [pulseaudio] protocol-native.c: Final latency 624,99 ms = 250,02 ms + 2*124,99 ms + 124,99 ms Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [alsa-sink] alsa-sink.c: Latency set to 124,99ms Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=330756 Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=87319 Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 90% 1: 90% Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,85 dB 1: -2,85 dB Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 90% 1: 90% Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,82 dB 1: -2,82 dB Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,03 dB 1: -0,03 dB Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [pulseaudio] reserve-wrap.c: Device lock status of reserve-monitor-wrapper@Audio2 changed: not busy Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.546| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:57:24 pulseaudio[2545]: last message repeated 3 times Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.562| 0.015) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.562| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.562| 0.000) [alsa-sink] alsa-sink.c: Limited to 21788 bytes. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.562| 0.000) [alsa-sink] alsa-sink.c: before: 5447 Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.562| 0.000) [alsa-sink] alsa-sink.c: after: 5447 Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.562| 0.000) [alsa-sink] alsa-sink.c: Rewound 21788 bytes. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.562| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.562| 0.000) [alsa-sink] sink.c: latency = 0 Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.562| 0.000) [alsa-sink] sink-input.c: Have to rewind 21788 bytes on render memblockq. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.562| 0.000) [alsa-sink] sink-input.c: Have to rewind 21788 bytes on render memblockq. Oct 27 20:57:24 caeser pulseaudio[2545]: ( 81.562| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:29 caeser pulseaudio[2545]: ( 86.871| 5.308) [alsa-sink] protocol-native.c: Underrun on 'ALSA Playback', 0 bytes in queue. Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.134| 0.263) [alsa-sink] alsa-sink.c: Latency set to 124,99ms Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.134| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=330756 Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=87319 Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 90% 1: 90% Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,85 dB 1: -2,85 dB Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 90% 1: 90% Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,82 dB 1: -2,82 dB Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,03 dB 1: -0,03 dB Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] alsa-sink.c: Limited to 17552 bytes. Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] alsa-sink.c: before: 4388 Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] alsa-sink.c: after: 4388 Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] alsa-sink.c: Rewound 17552 bytes. Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes idle, timeout in 5 seconds. Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] sink.c: latency = 10132 Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] sink-input.c: Have to rewind 17552 bytes on render memblockq. Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes idle, timeout in 5 seconds. Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [pulseaudio] sink-input.c: Freeing input 2 "ALSA Playback" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [pulseaudio] ratelimit.c: 357 events suppressed Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.135| 0.000) [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry) Oct 27 20:57:30 pulseaudio[2545]: last message repeated 10 times Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.002) [pulseaudio] module-stream-restore.c: Restoring device for stream sink-input-by-application-name:ALSA plug-in [firefox]. Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] module-intended-roles.c: Not setting device for stream ALSA Playback, because already set. Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-application-name:ALSA plug-in [firefox]. Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes busy. Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] sink-input.c: Created input 3 "ALSA Playback" on alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo with sample spec s16le 2ch 44100Hz and channel map front-left,front-right Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] sink-input.c: media.name = "ALSA Playback" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] sink-input.c: application.name = "ALSA plug-in [firefox]" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] sink-input.c: native-protocol.version = "24" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] sink-input.c: application.process.id = "3041" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] sink-input.c: application.process.user = "raphael" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] sink-input.c: application.process.host = "caeser" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] sink-input.c: application.process.binary = "firefox" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] sink-input.c: window.x11.display = ":0" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] sink-input.c: application.language = "de_DE.UTF-8" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] sink-input.c: application.process.machine_id = "de3399e1c0401d2cf76639074bdc22c5" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] sink-input.c: application.process.session_id = "de3399e1c0401d2cf76639074bdc22c5-1319733621.487632-440420476" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] sink-input.c: application.icon_name = "firefox" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.138| 0.000) [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-application-name:ALSA plug-in [firefox]" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.139| 0.000) [pulseaudio] protocol-native.c: Requested tlength=500,00 ms, minreq=124,99 ms Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.139| 0.000) [pulseaudio] protocol-native.c: Early requests mode enabled, configuring sink latency to minreq. Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.139| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=88200, base=4, prebuf=66152, minreq=22048 maxrewind=0 Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.139| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=88200, base=4, prebuf=66152, minreq=22048 maxrewind=0 Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.139| 0.000) [pulseaudio] protocol-native.c: Final latency 624,99 ms = 250,02 ms + 2*124,99 ms + 124,99 ms Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.139| 0.000) [alsa-sink] alsa-sink.c: Latency set to 124,99ms Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.139| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=330756 Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.139| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=87319 Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.139| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 90% 1: 90% Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.139| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,85 dB 1: -2,85 dB Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.139| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 90% 1: 90% Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.139| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,82 dB 1: -2,82 dB Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.139| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.139| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,03 dB 1: -0,03 dB Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.139| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.463) [alsa-sink] alsa-sink.c: Latency set to 124,99ms Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=330756 Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=87319 Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 90% 1: 90% Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,85 dB 1: -2,85 dB Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 90% 1: 90% Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,82 dB 1: -2,82 dB Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,03 dB 1: -0,03 dB Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] alsa-sink.c: Limited to 13320 bytes. Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] alsa-sink.c: before: 3330 Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] alsa-sink.c: after: 3330 Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] alsa-sink.c: Rewound 13320 bytes. Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] sink.c: latency = 12735 Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] sink-input.c: Have to rewind 13320 bytes on render memblockq. Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [pulseaudio] sink-input.c: Freeing input 1 "ALSA Playback" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [pulseaudio] client.c: Freed 20 "ALSA plug-in [firefox]" Oct 27 20:57:30 caeser pulseaudio[2545]: ( 87.603| 0.000) [pulseaudio] protocol-native.c: Connection died. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.231| 0.628) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.231| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.231| 0.000) [alsa-sink] alsa-sink.c: Limited to 14732 bytes. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.231| 0.000) [alsa-sink] alsa-sink.c: before: 3683 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.231| 0.000) [alsa-sink] alsa-sink.c: after: 3683 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.231| 0.000) [alsa-sink] alsa-sink.c: Rewound 14732 bytes. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.231| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.231| 0.000) [alsa-sink] sink.c: latency = 6723 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.231| 0.000) [alsa-sink] sink-input.c: Have to rewind 14732 bytes on render memblockq. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.231| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.629| 0.397) [alsa-sink] protocol-native.c: Underrun on 'ALSA Playback', 0 bytes in queue. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.644| 0.014) [alsa-sink] alsa-sink.c: hwbuf_unused=0 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.644| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=87319 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.644| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 90% 1: 90% Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,85 dB 1: -2,85 dB Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 90% 1: 90% Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,82 dB 1: -2,82 dB Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,03 dB 1: -0,03 dB Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [alsa-sink] alsa-sink.c: Limited to 18964 bytes. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [alsa-sink] alsa-sink.c: before: 4741 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [alsa-sink] alsa-sink.c: after: 4741 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [alsa-sink] alsa-sink.c: Rewound 18964 bytes. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes idle, timeout in 5 seconds. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [alsa-sink] sink.c: latency = 10151 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes idle, timeout in 5 seconds. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.645| 0.000) [pulseaudio] sink-input.c: Freeing input 3 "ALSA Playback" Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.001) [pulseaudio] module-stream-restore.c: Restoring device for stream sink-input-by-application-name:ALSA plug-in [firefox]. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] module-intended-roles.c: Not setting device for stream ALSA Playback, because already set. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-application-name:ALSA plug-in [firefox]. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes busy. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] sink-input.c: Created input 4 "ALSA Playback" on alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo with sample spec s16le 2ch 44100Hz and channel map front-left,front-right Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] sink-input.c: media.name = "ALSA Playback" Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] sink-input.c: application.name = "ALSA plug-in [firefox]" Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] sink-input.c: native-protocol.version = "24" Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] sink-input.c: application.process.id = "3041" Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] sink-input.c: application.process.user = "raphael" Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] sink-input.c: application.process.host = "caeser" Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] sink-input.c: application.process.binary = "firefox" Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] sink-input.c: window.x11.display = ":0" Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] sink-input.c: application.language = "de_DE.UTF-8" Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] sink-input.c: application.process.machine_id = "de3399e1c0401d2cf76639074bdc22c5" Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] sink-input.c: application.process.session_id = "de3399e1c0401d2cf76639074bdc22c5-1319733621.487632-440420476" Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] sink-input.c: application.icon_name = "firefox" Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.647| 0.000) [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-application-name:ALSA plug-in [firefox]" Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [pulseaudio] protocol-native.c: Requested tlength=500,00 ms, minreq=124,99 ms Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [pulseaudio] protocol-native.c: Early requests mode enabled, configuring sink latency to minreq. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=88200, base=4, prebuf=66152, minreq=22048 maxrewind=0 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=88200, base=4, prebuf=66152, minreq=22048 maxrewind=0 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [pulseaudio] protocol-native.c: Final latency 624,99 ms = 250,02 ms + 2*124,99 ms + 124,99 ms Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] alsa-sink.c: Latency set to 124,99ms Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=330756 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=87319 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] alsa-sink.c: Requesting rewind due to latency change. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 90% 1: 90% Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,85 dB 1: -2,85 dB Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 90% 1: 90% Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,82 dB 1: -2,82 dB Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,03 dB 1: -0,03 dB Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] alsa-sink.c: Limited to 352544 bytes. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] alsa-sink.c: before: 88136 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] alsa-sink.c: after: 88136 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] alsa-sink.c: Rewound 352544 bytes. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] sink.c: latency = 6507 Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] sink-input.c: Have to rewind 352544 bytes on render memblockq. Oct 27 20:57:31 caeser pulseaudio[2545]: ( 88.648| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:33 caeser pulseaudio[2545]: ( 90.628| 1.979) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 27 20:57:33 caeser pulseaudio[2545]: ( 90.628| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:33 caeser pulseaudio[2545]: ( 90.628| 0.000) [alsa-sink] alsa-sink.c: Limited to 21788 bytes. Oct 27 20:57:33 caeser pulseaudio[2545]: ( 90.628| 0.000) [alsa-sink] alsa-sink.c: before: 5447 Oct 27 20:57:33 caeser pulseaudio[2545]: ( 90.628| 0.000) [alsa-sink] alsa-sink.c: after: 5447 Oct 27 20:57:33 caeser pulseaudio[2545]: ( 90.628| 0.000) [alsa-sink] alsa-sink.c: Rewound 21788 bytes. Oct 27 20:57:33 caeser pulseaudio[2545]: ( 90.628| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:33 caeser pulseaudio[2545]: ( 90.628| 0.000) [alsa-sink] sink.c: latency = 12211 Oct 27 20:57:33 caeser pulseaudio[2545]: ( 90.628| 0.000) [alsa-sink] sink-input.c: Have to rewind 21788 bytes on render memblockq. Oct 27 20:57:33 caeser pulseaudio[2545]: ( 90.628| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.710| 1.082) [alsa-sink] protocol-native.c: Underrun on 'ALSA Playback', 0 bytes in queue. Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.711| 0.001) [alsa-sink] alsa-sink.c: hwbuf_unused=0 Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.711| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=87319 Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.711| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 90% 1: 90% Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.711| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,85 dB 1: -2,85 dB Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.711| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 90% 1: 90% Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.711| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,82 dB 1: -2,82 dB Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.711| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.712| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,03 dB 1: -0,03 dB Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.712| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.712| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.712| 0.000) [alsa-sink] alsa-sink.c: Limited to 21788 bytes. Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.712| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes idle, timeout in 5 seconds. Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.712| 0.000) [alsa-sink] alsa-sink.c: before: 5447 Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.712| 0.000) [alsa-sink] alsa-sink.c: after: 5447 Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.712| 0.000) [alsa-sink] alsa-sink.c: Rewound 21788 bytes. Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.712| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.712| 0.000) [alsa-sink] sink.c: latency = 7948 Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.712| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:34 caeser pulseaudio[2545]: ( 91.712| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes idle, timeout in 5 seconds. Oct 27 20:57:37 caeser pulseaudio[2545]: ( 94.769| 2.411) [alsa-source] alsa-source.c: hwbuf_unused=0 Oct 27 20:57:37 caeser pulseaudio[2545]: ( 94.769| 0.000) [alsa-source] alsa-source.c: setting avail_min=87751 Oct 27 20:57:37 caeser pulseaudio[2545]: ( 94.769| 0.000) [alsa-source] alsa-source.c: Requested volume: 0: 84% 1: 84% Oct 27 20:57:37 caeser pulseaudio[2545]: ( 94.769| 0.000) [alsa-source] alsa-source.c: in dB: 0: -4,50 dB 1: -4,50 dB Oct 27 20:57:37 caeser pulseaudio[2545]: ( 94.769| 0.000) [alsa-source] alsa-source.c: Got hardware volume: 0: 84% 1: 84% Oct 27 20:57:37 caeser pulseaudio[2545]: ( 94.769| 0.000) [alsa-source] alsa-source.c: in dB: 0: -4,50 dB 1: -4,50 dB Oct 27 20:57:37 caeser pulseaudio[2545]: ( 94.769| 0.000) [alsa-source] alsa-source.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:37 caeser pulseaudio[2545]: ( 94.769| 0.000) [alsa-source] alsa-source.c: in dB: 0: 0,00 dB 1: 0,00 dB Oct 27 20:57:37 caeser pulseaudio[2545]: ( 94.769| 0.000) [alsa-source] source.c: Volume not changing Oct 27 20:57:37 caeser pulseaudio[2545]: ( 94.769| 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 27 20:57:37 caeser pulseaudio[2545]: ( 94.769| 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 27 20:57:37 caeser pulseaudio[2545]: ( 94.769| 0.000) [pulseaudio] source-output.c: Freeing output 1 "Peak detect" Oct 27 20:57:37 caeser pulseaudio[2545]: ( 94.769| 0.000) [pulseaudio] client.c: Freed 21 "GNOME Volume Control Dialog" Oct 27 20:57:37 caeser pulseaudio[2545]: ( 94.769| 0.000) [pulseaudio] protocol-native.c: Connection died. Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.394| 2.624) [alsa-sink] sink-input.c: Requesting rewind due to corking Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.394| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 0 bytes. Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.394| 0.000) [alsa-sink] alsa-sink.c: Mhmm, actually there is nothing to rewind. Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.394| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes idle, timeout in 5 seconds. Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.394| 0.000) [alsa-sink] ratelimit.c: 506 events suppressed Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.394| 0.000) [alsa-sink] flist.c: pulsecore/hashmap.c: entries flist is full (don't worry) Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.394| 0.000) [alsa-sink] flist.c: pulsecore/hashmap.c: entries flist is full (don't worry) Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] flist.c: pulsecore/hashmap.c: entries flist is full (don't worry) Oct 27 20:57:40 pulseaudio[2545]: last message repeated 8 times Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=0 Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=87319 Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 90% 1: 90% Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,85 dB 1: -2,85 dB Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 90% 1: 90% Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -2,82 dB 1: -2,82 dB Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,03 dB 1: -0,03 dB Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] alsa-sink.c: Limited to 14732 bytes. Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] alsa-sink.c: before: 3683 Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] alsa-sink.c: after: 3683 Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] alsa-sink.c: Rewound 14732 bytes. Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] sink.c: latency = 12850 Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.395| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes idle, timeout in 5 seconds. Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.396| 0.000) [pulseaudio] core.c: Hmm, no streams around, trying to vacuum. Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.396| 0.000) [pulseaudio] sink-input.c: Freeing input 5 "ALSA Playback" Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.396| 0.000) [pulseaudio] client.c: Freed 22 "ALSA plug-in [firefox]" Oct 27 20:57:40 caeser pulseaudio[2545]: ( 97.396| 0.000) [pulseaudio] protocol-native.c: Connection died. Oct 27 20:57:42 caeser pulseaudio[2545]: ( 99.769| 2.372) [pulseaudio] module-suspend-on-idle.c: Source alsa_input.pci-0000_00_1b.0.analog-stereo idle for too long, suspending ... Oct 27 20:57:42 caeser pulseaudio[2545]: ( 99.769| 0.000) [pulseaudio] source.c: Suspend cause of source alsa_input.pci-0000_00_1b.0.analog-stereo is 0x0004, suspending Oct 27 20:57:42 caeser pulseaudio[2545]: ( 99.776| 0.006) [alsa-source] alsa-source.c: Device suspended... Oct 27 20:57:42 caeser pulseaudio[2545]: ( 99.776| 0.000) [pulseaudio] core.c: Hmm, no streams around, trying to vacuum. Oct 27 20:57:42 caeser pulseaudio[2545]: ( 99.776| 0.000) [pulseaudio] reserve-wrap.c: Device lock status of reserve-monitor-wrapper@Audio0 changed: not busy Oct 27 20:57:42 caeser pulseaudio[2545]: ( 99.776| 0.000) [pulseaudio] module-udev-detect.c: /dev/snd/controlC0 is accessible: yes Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.450| 0.673) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.450| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 86% 1: 86% Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.450| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -4,04 dB 1: -4,04 dB Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.450| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 86% 1: 86% Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.450| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -3,94 dB 1: -3,94 dB Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.450| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.450| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,10 dB 1: -0,10 dB Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.450| 0.000) [alsa-sink] sink.c: Volume going down to 56126 at 8283910411 Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.450| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.479| 0.029) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.480| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 82% 1: 82% Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.480| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -5,29 dB 1: -5,29 dB Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.480| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 82% 1: 82% Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.480| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -5,25 dB 1: -5,25 dB Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.480| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.480| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,04 dB 1: -0,04 dB Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.480| 0.000) [alsa-sink] sink.c: Volume going down to 53504 at 8283910420 Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.480| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.786| 0.305) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.786| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 78% 1: 78% Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.786| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -6,59 dB 1: -6,59 dB Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.786| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 78% 1: 78% Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.786| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -6,57 dB 1: -6,57 dB Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.786| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.786| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,03 dB 1: -0,03 dB Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.786| 0.000) [alsa-sink] sink.c: Volume going down to 50882 at 8283911022 Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.786| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.810| 0.023) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.810| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 74% 1: 74% Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.810| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -7,97 dB 1: -7,97 dB Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.810| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 74% 1: 74% Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.810| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -7,88 dB 1: -7,88 dB Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.810| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.810| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,09 dB 1: -0,09 dB Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.810| 0.000) [alsa-sink] sink.c: Volume going down to 48260 at 8283911097 Oct 27 20:57:43 caeser pulseaudio[2545]: ( 100.810| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.093| 0.282) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.093| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 70% 1: 70% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.093| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -9,43 dB 1: -9,43 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.093| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 70% 1: 70% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.093| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -9,38 dB 1: -9,38 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.093| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.093| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,05 dB 1: -0,05 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.093| 0.000) [alsa-sink] sink.c: Volume going down to 45638 at 8283912046 Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.093| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.113| 0.020) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.114| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 66% 1: 66% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.114| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -10,97 dB 1: -10,97 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.114| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 66% 1: 66% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.114| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -10,88 dB 1: -10,88 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.114| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.114| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,09 dB 1: -0,09 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.114| 0.000) [alsa-sink] sink.c: Volume going down to 43016 at 8283912110 Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.114| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.346| 0.231) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.346| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 62% 1: 62% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.346| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -12,61 dB 1: -12,61 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.346| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 62% 1: 62% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.346| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -12,57 dB 1: -12,57 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.346| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.346| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,04 dB 1: -0,04 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.346| 0.000) [alsa-sink] sink.c: Volume going down to 40394 at 8283912671 Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.346| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.376| 0.030) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.377| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 58% 1: 58% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.377| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -14,36 dB 1: -14,36 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.377| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 58% 1: 58% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.377| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -14,25 dB 1: -14,25 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.377| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.377| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,11 dB 1: -0,11 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.377| 0.000) [alsa-sink] sink.c: Volume going down to 37772 at 8283912711 Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.377| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.381| 0.004) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.382| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 58% 1: 58% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.382| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -14,36 dB 1: -14,36 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.382| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 58% 1: 58% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.382| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -14,25 dB 1: -14,25 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.382| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.382| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,11 dB 1: -0,11 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.382| 0.000) [alsa-sink] sink.c: Volume going down to 37772 at 8283912716 Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.594| 0.211) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.594| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 54% 1: 54% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.594| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -16,23 dB 1: -16,23 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.594| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 54% 1: 54% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.594| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -16,13 dB 1: -16,13 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.594| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.594| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,10 dB 1: -0,10 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.594| 0.000) [alsa-sink] sink.c: Volume going down to 35150 at 8285056798 Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.594| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.610| 0.015) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.610| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 50% 1: 50% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.610| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -18,25 dB 1: -18,25 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.610| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 50% 1: 50% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.610| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -18,19 dB 1: -18,19 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.610| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.610| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,06 dB 1: -0,06 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.610| 0.000) [alsa-sink] sink.c: Volume going down to 32528 at 8285056802 Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.610| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.626| 0.016) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.626| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 46% 1: 46% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.626| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -20,44 dB 1: -20,44 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.626| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 46% 1: 46% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.626| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -20,44 dB 1: -20,44 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.626| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.626| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,00 dB 1: -0,00 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.626| 0.000) [alsa-sink] sink.c: Volume going down to 29906 at 8285056805 Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.626| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.842| 0.215) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.842| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 42% 1: 42% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.842| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -22,83 dB 1: -22,83 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.842| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 42% 1: 42% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.842| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -22,69 dB 1: -22,69 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.842| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 99% 1: 99% (accurate-enough=yes) Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.842| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,14 dB 1: -0,14 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.842| 0.000) [alsa-sink] sink.c: Volume going down to 27284 at 8285056815 Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.842| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.859| 0.017) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.860| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 38% 1: 38% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.860| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -25,47 dB 1: -25,47 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.860| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 38% 1: 38% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.860| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -25,32 dB 1: -25,32 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.860| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 99% 1: 99% (accurate-enough=yes) Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.860| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,15 dB 1: -0,15 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.860| 0.000) [alsa-sink] sink.c: Volume going down to 24662 at 8285056812 Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.860| 0.000) [alsa-sink] sink.c: Volume change to 27284 at 8285056815 was dropped Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.861| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.866| 0.005) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.866| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 34% 1: 34% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.866| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -28,40 dB 1: -28,40 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.866| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 34% 1: 34% Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.866| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -28,31 dB 1: -28,31 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.866| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 100% 1: 100% (accurate-enough=yes) Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.866| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,09 dB 1: -0,09 dB Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.866| 0.000) [alsa-sink] sink.c: Volume going down to 22040 at 8285056811 Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.866| 0.000) [alsa-sink] sink.c: Volume change to 24662 at 8285056812 was dropped Oct 27 20:57:44 caeser pulseaudio[2545]: ( 101.866| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.090| 0.224) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.090| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 30% 1: 30% Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.090| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -31,70 dB 1: -31,70 dB Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.090| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 34% 1: 34% Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.090| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -28,31 dB 1: -28,31 dB Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.090| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 88% 1: 88% (accurate-enough=no) Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.090| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -3,39 dB 1: -3,39 dB Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.090| 0.000) [alsa-sink] sink.c: Volume going up to 22113 at 8285072765 Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.090| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.090| 0.000) [alsa-sink] alsa-sink.c: Limited to 265048 bytes. Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.090| 0.000) [alsa-sink] alsa-sink.c: before: 66262 Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.090| 0.000) [alsa-sink] alsa-sink.c: after: 66262 Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.091| 0.000) [alsa-sink] alsa-sink.c: Rewound 265048 bytes. Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.091| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.091| 0.000) [alsa-sink] sink.c: latency = 9064 Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.091| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.091| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.092| 0.000) [alsa-sink] sink.c: Volume change to 56126 at 8283554235 was written 15 usec late Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.092| 0.000) [alsa-sink] sink.c: Volume change to 53504 at 8283554235 was written 15 usec late Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.092| 0.000) [alsa-sink] sink.c: Volume change to 50882 at 8283554235 was written 15 usec late Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.092| 0.000) [alsa-sink] sink.c: Volume change to 48260 at 8283554235 was written 15 usec late Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.092| 0.000) [alsa-sink] sink.c: Volume change to 45638 at 8283554235 was written 15 usec late Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.092| 0.000) [alsa-sink] sink.c: Volume change to 43016 at 8283554235 was written 15 usec late Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.092| 0.000) [alsa-sink] sink.c: Volume change to 40394 at 8283554235 was written 15 usec late Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.092| 0.000) [alsa-sink] sink.c: Volume change to 37772 at 8283554235 was written 15 usec late Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.108| 0.015) [alsa-sink] sink.c: Volume change to 37772 at 8283570235 was written 24 usec late Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.108| 0.000) [alsa-sink] sink.c: Volume change to 35150 at 8283554259 was written 16000 usec late Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.108| 0.000) [alsa-sink] sink.c: Volume change to 32528 at 8283554263 was written 15996 usec late Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.108| 0.000) [alsa-sink] sink.c: Volume change to 29906 at 8283554266 was written 15993 usec late Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.108| 0.000) [alsa-sink] sink.c: Volume change to 22040 at 8283554272 was written 15987 usec late Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.108| 0.000) [alsa-sink] sink.c: Volume change to 22113 at 8283570235 was written 24 usec late Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.110| 0.002) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.111| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 26% 1: 26% Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.111| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -35,48 dB 1: -35,48 dB Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.111| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 34% 1: 34% Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.111| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -28,31 dB 1: -28,31 dB Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.111| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 76% 1: 76% (accurate-enough=no) Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.111| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -7,17 dB 1: -7,17 dB Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.111| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.111| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.111| 0.000) [alsa-sink] alsa-sink.c: Limited to 348312 bytes. Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.111| 0.000) [alsa-sink] alsa-sink.c: before: 87078 Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.111| 0.000) [alsa-sink] alsa-sink.c: after: 87078 Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.111| 0.000) [alsa-sink] alsa-sink.c: Rewound 348312 bytes. Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.111| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.111| 0.000) [alsa-sink] sink.c: latency = 12827 Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.112| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.111| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.128| 0.016) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.128| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 22% 1: 22% Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.128| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -39,90 dB 1: -39,90 dB Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.128| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 34% 1: 34% Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.128| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -28,31 dB 1: -28,31 dB Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.128| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 64% 1: 64% (accurate-enough=no) Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.128| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -11,59 dB 1: -11,59 dB Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.128| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.128| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.128| 0.000) [alsa-sink] alsa-sink.c: Limited to 349720 bytes. Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.128| 0.000) [alsa-sink] alsa-sink.c: before: 87430 Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.128| 0.000) [alsa-sink] alsa-sink.c: after: 87430 Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.128| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.128| 0.000) [alsa-sink] alsa-sink.c: Rewound 349720 bytes. Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.128| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:45 caeser pulseaudio[2545]: ( 102.128| 0.000) [alsa-sink] sink.c: latency = 11652 Oct 27 20:57:53 caeser pulseaudio[2545]: ( 110.457| 7.441) [pulseaudio] module-device-restore.c: Synced. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.452| 1.994) [pulseaudio] client.c: Created 23 "Native client (UNIX socket client)" Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.452| 0.000) [pulseaudio] protocol-native.c: Protocol version: remote 24, local 24 Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.452| 0.000) [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1 Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.452| 0.000) [pulseaudio] protocol-native.c: SHM possible: yes Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.452| 0.000) [pulseaudio] protocol-native.c: Negotiated SHM: yes Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.452| 0.000) [pulseaudio] module-augment-properties.c: Looking for .desktop file for firefox Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.452| 0.000) [pulseaudio] module-stream-restore.c: Restoring device for stream sink-input-by-application-name:ALSA plug-in [firefox]. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.452| 0.000) [pulseaudio] module-intended-roles.c: Not setting device for stream ALSA Playback, because already set. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.453| 0.000) [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-application-name:ALSA plug-in [firefox]. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.453| 0.000) [pulseaudio] sink.c: Suspend cause of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo is 0x0000, resuming Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.457| 0.004) [pulseaudio] reserve-wrap.c: Successfully acquired reservation lock on device 'Audio2' Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.457| 0.000) [alsa-sink] alsa-sink.c: Trying resume... Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.457| 0.000) [alsa-sink] alsa-util.c: cannot disable ALSA period wakeups Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.457| 0.000) [alsa-sink] alsa-util.c: Maximum hw buffer size is 5944 ms Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.461| 0.003) [alsa-sink] alsa-util.c: Set buffer size first (to 88200 samples), period size second (to 44100 samples). Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.002) [alsa-sink] alsa-util.c: ALSA period wakeups were not disabled Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=0 Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=87319 Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [alsa-sink] alsa-sink.c: Resumed successfully... Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [alsa-sink] alsa-sink.c: Starting playback. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes idle, timeout in 5 seconds. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo becomes busy. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] sink-input.c: Created input 6 "ALSA Playback" on alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo with sample spec s16le 2ch 44100Hz and channel map front-left,front-right Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] sink-input.c: media.name = "ALSA Playback" Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] sink-input.c: application.name = "ALSA plug-in [firefox]" Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] sink-input.c: native-protocol.version = "24" Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] sink-input.c: application.process.id = "3041" Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] sink-input.c: application.process.user = "raphael" Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] sink-input.c: application.process.host = "caeser" Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] sink-input.c: application.process.binary = "firefox" Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] sink-input.c: window.x11.display = ":0" Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] sink-input.c: application.language = "de_DE.UTF-8" Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] sink-input.c: application.process.machine_id = "de3399e1c0401d2cf76639074bdc22c5" Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] sink-input.c: application.process.session_id = "de3399e1c0401d2cf76639074bdc22c5-1319733621.487632-440420476" Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] sink-input.c: application.icon_name = "firefox" Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-application-name:ALSA plug-in [firefox]" Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] protocol-native.c: Requested tlength=500,00 ms, minreq=124,99 ms Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [pulseaudio] protocol-native.c: Early requests mode enabled, configuring sink latency to minreq. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.464| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=88200, base=4, prebuf=66152, minreq=22048 maxrewind=0 Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=88200, base=4, prebuf=66152, minreq=22048 maxrewind=0 Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [pulseaudio] protocol-native.c: Final latency 624,99 ms = 250,02 ms + 2*124,99 ms + 124,99 ms Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: Latency set to 124,99ms Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: hwbuf_unused=330756 Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: setting avail_min=87319 Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: Requesting rewind due to latency change. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 0% 1: 0% Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -inf dB 1: -inf dB Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 34% 1: 34% Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -28,31 dB 1: -28,31 dB Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 0% 1: 0% (accurate-enough=no) Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -inf dB 1: -inf dB Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: Limited to 352544 bytes. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: before: 88136 Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: after: 88136 Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] alsa-sink.c: Rewound 352544 bytes. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] sink.c: latency = 668 Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] sink-input.c: Have to rewind 352544 bytes on render memblockq. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.465| 0.000) [pulseaudio] reserve-wrap.c: Device lock status of reserve-monitor-wrapper@Audio2 changed: not busy Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.468| 0.003) [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.468| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.468| 0.000) [alsa-sink] alsa-sink.c: Limited to 21788 bytes. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.468| 0.000) [alsa-sink] alsa-sink.c: before: 5447 Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.468| 0.000) [alsa-sink] alsa-sink.c: after: 5447 Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.468| 0.000) [alsa-sink] alsa-sink.c: Rewound 21788 bytes. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.468| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.468| 0.000) [alsa-sink] sink.c: latency = 0 Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.468| 0.000) [alsa-sink] sink-input.c: Have to rewind 21788 bytes on render memblockq. Oct 27 20:57:55 caeser pulseaudio[2545]: ( 112.468| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 1.741) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 4% 1: 4% Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -83,88 dB 1: -83,88 dB Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 34% 1: 34% Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -28,31 dB 1: -28,31 dB Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 12% 1: 12% (accurate-enough=no) Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -55,57 dB 1: -55,57 dB Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] sink.c: Volume going up to 22112 at 8295831509 Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] sink.c: Next volume change in 158705 usec Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] alsa-sink.c: Limited to 21788 bytes. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] alsa-sink.c: before: 5447 Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] alsa-sink.c: after: 5447 Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] alsa-sink.c: Rewound 21788 bytes. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] sink.c: latency = 27096 Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] sink.c: Next volume change in 35096 usec Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] sink-input.c: Have to rewind 21788 bytes on render memblockq. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] sink.c: Next volume change in 34973 usec Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] sink.c: Next volume change in 34936 usec Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.210| 0.000) [alsa-sink] sink.c: Next volume change in 34901 usec Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.245| 0.034) [alsa-sink] sink.c: Volume change to 22112 at 8295708003 was written 32 usec late Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.015) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 8% 1: 8% Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -65,82 dB 1: -65,82 dB Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 34% 1: 34% Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -28,31 dB 1: -28,31 dB Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 24% 1: 24% (accurate-enough=no) Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -37,51 dB 1: -37,51 dB Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [alsa-sink] alsa-sink.c: Limited to 13324 bytes. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [alsa-sink] alsa-sink.c: before: 3331 Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [alsa-sink] alsa-sink.c: after: 3331 Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [alsa-sink] alsa-sink.c: Rewound 13324 bytes. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [alsa-sink] sink.c: latency = 23530 Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [alsa-sink] sink-input.c: Have to rewind 13324 bytes on render memblockq. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.261| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.608| 0.347) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.608| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 12% 1: 12% Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.609| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -55,25 dB 1: -55,25 dB Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.609| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 34% 1: 34% Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.609| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -28,31 dB 1: -28,31 dB Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.609| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 36% 1: 36% (accurate-enough=no) Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.609| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -26,94 dB 1: -26,94 dB Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.609| 0.000) [alsa-sink] sink.c: Volume going up to 22113 at 8296187248 Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.609| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.609| 0.000) [alsa-sink] alsa-sink.c: Limited to 16144 bytes. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.609| 0.000) [alsa-sink] alsa-sink.c: before: 4036 Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.609| 0.000) [alsa-sink] alsa-sink.c: after: 4036 Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.609| 0.000) [alsa-sink] alsa-sink.c: Rewound 16144 bytes. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.609| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.609| 0.000) [alsa-sink] sink.c: latency = 16370 Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.609| 0.000) [alsa-sink] sink-input.c: Have to rewind 16144 bytes on render memblockq. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.609| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.609| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.633| 0.024) [alsa-sink] sink.c: Volume change to 22113 at 8296095757 was written 26 usec late Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.922| 0.288) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.922| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 16% 1: 16% Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.922| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -47,76 dB 1: -47,76 dB Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.922| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 34% 1: 34% Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.922| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -28,31 dB 1: -28,31 dB Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.922| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 47% 1: 47% (accurate-enough=no) Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.922| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -19,45 dB 1: -19,45 dB Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.922| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.922| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.922| 0.000) [alsa-sink] alsa-sink.c: Limited to 20376 bytes. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.922| 0.000) [alsa-sink] alsa-sink.c: before: 5094 Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.922| 0.000) [alsa-sink] alsa-sink.c: after: 5094 Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.923| 0.000) [alsa-sink] alsa-sink.c: Rewound 20376 bytes. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.923| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.923| 0.000) [alsa-sink] sink.c: latency = 20369 Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.923| 0.000) [alsa-sink] sink-input.c: Have to rewind 20376 bytes on render memblockq. Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.923| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:57 caeser pulseaudio[2545]: ( 114.923| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.042) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 20% 1: 20% Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -41,94 dB 1: -41,94 dB Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 34% 1: 34% Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -28,31 dB 1: -28,31 dB Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 59% 1: 59% (accurate-enough=no) Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -13,63 dB 1: -13,63 dB Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.000) [alsa-sink] alsa-sink.c: Limited to 14732 bytes. Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.000) [alsa-sink] alsa-sink.c: before: 3683 Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.000) [alsa-sink] alsa-sink.c: after: 3683 Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.000) [alsa-sink] alsa-sink.c: Rewound 14732 bytes. Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.000) [alsa-sink] sink.c: latency = 16183 Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.000) [alsa-sink] sink-input.c: Have to rewind 14732 bytes on render memblockq. Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.966| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:58 caeser pulseaudio[2545]: ( 114.967| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.259) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 24% 1: 24% Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -37,19 dB 1: -37,19 dB Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 34% 1: 34% Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -28,31 dB 1: -28,31 dB Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 71% 1: 71% (accurate-enough=no) Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -8,88 dB 1: -8,88 dB Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [alsa-sink] sink.c: Volume not changing Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [alsa-sink] alsa-sink.c: Limited to 21788 bytes. Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [alsa-sink] alsa-sink.c: before: 5447 Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [alsa-sink] alsa-sink.c: after: 5447 Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [alsa-sink] alsa-sink.c: Rewound 21788 bytes. Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [alsa-sink] sink.c: latency = 18218 Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [alsa-sink] sink-input.c: Have to rewind 21788 bytes on render memblockq. Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.226| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.626| 0.399) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:57:58 caeser pulseaudio[2545]: ( 115.626| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 28% 1: 28% Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.723) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 36% 1: 36% Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -26,63 dB 1: -26,63 dB Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 36% 1: 36% Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -26,44 dB 1: -26,44 dB Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 99% 1: 99% (accurate-enough=yes) Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,19 dB 1: -0,19 dB Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [alsa-sink] sink.c: Volume going up to 23588 at 8299937293 Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [alsa-sink] alsa-sink.c: Limited to 17556 bytes. Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [alsa-sink] alsa-sink.c: before: 4389 Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [alsa-sink] alsa-sink.c: after: 4389 Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [alsa-sink] alsa-sink.c: Rewound 17556 bytes. Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [alsa-sink] sink.c: latency = 13194 Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [alsa-sink] sink-input.c: Have to rewind 17556 bytes on render memblockq. Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.354| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:58:01 caeser pulseaudio[2545]: ( 118.375| 0.021) [alsa-sink] sink.c: Volume change to 23588 at 8299837778 was written 22 usec late Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.141| 1.766) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.141| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 32% 1: 32% Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.141| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -29,70 dB 1: -29,70 dB Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.141| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 34% 1: 34% Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.141| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -28,31 dB 1: -28,31 dB Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.141| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 95% 1: 95% (accurate-enough=no) Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.142| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -1,39 dB 1: -1,39 dB Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.142| 0.000) [alsa-sink] sink.c: Volume going down to 22113 at 8301729380 Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.142| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.142| 0.000) [alsa-sink] alsa-sink.c: Limited to 21788 bytes. Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.142| 0.000) [alsa-sink] alsa-sink.c: before: 5447 Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.142| 0.000) [alsa-sink] alsa-sink.c: after: 5447 Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.142| 0.000) [alsa-sink] alsa-sink.c: Rewound 21788 bytes. Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.142| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.142| 0.000) [alsa-sink] sink.c: latency = 9599 Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.142| 0.000) [alsa-sink] sink-input.c: Have to rewind 21788 bytes on render memblockq. Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.142| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.142| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:58:03 caeser pulseaudio[2545]: ( 120.143| 0.001) [alsa-sink] sink.c: Volume change to 22113 at 8301605996 was written 24 usec late Oct 27 20:58:07 caeser pulseaudio[2545]: ( 124.210| 4.066) [pulseaudio] module-device-restore.c: Synced. Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.343| 2.133) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 36% 1: 36% Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -26,63 dB 1: -26,63 dB Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 36% 1: 36% Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -26,44 dB 1: -26,44 dB Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 99% 1: 99% (accurate-enough=yes) Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -0,19 dB 1: -0,19 dB Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] sink.c: Volume going up to 23587 at 8307944183 Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] ratelimit.c: 98 events suppressed Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] sink.c: Next volume change in 137870 usec Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] alsa-sink.c: Limited to 21788 bytes. Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] alsa-sink.c: before: 5447 Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] alsa-sink.c: after: 5447 Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] alsa-sink.c: Rewound 21788 bytes. Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] sink.c: latency = 6155 Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] sink.c: Next volume change in 14154 usec Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] sink-input.c: Have to rewind 21788 bytes on render memblockq. Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] sink.c: Next volume change in 14018 usec Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.344| 0.000) [alsa-sink] sink.c: Next volume change in 13583 usec Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.345| 0.000) [alsa-sink] sink.c: Next volume change in 13522 usec Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.345| 0.000) [alsa-sink] sink.c: Next volume change in 13506 usec Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.345| 0.000) [alsa-sink] sink.c: Next volume change in 13493 usec Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.345| 0.000) [alsa-sink] sink.c: Next volume change in 13389 usec Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.345| 0.000) [alsa-sink] sink.c: Next volume change in 13370 usec Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.345| 0.000) [alsa-sink] sink.c: Next volume change in 13356 usec Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.345| 0.000) [alsa-sink] sink.c: Next volume change in 13344 usec Oct 27 20:58:09 caeser pulseaudio[2545]: ( 126.358| 0.013) [alsa-sink] sink.c: Volume change to 23587 at 8307820694 was written 95 usec late Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 1.084) [pulseaudio] protocol-native.c: Client indicator-sound-service changes volume of sink alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo. Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 0.000) [alsa-sink] alsa-sink.c: Requested volume: 0: 32% 1: 32% Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -29,70 dB 1: -29,70 dB Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 0.000) [alsa-sink] alsa-sink.c: Got hardware volume: 0: 34% 1: 34% Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -28,31 dB 1: -28,31 dB Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 0.000) [alsa-sink] alsa-sink.c: Calculated software volume: 0: 95% 1: 95% (accurate-enough=no) Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 0.000) [alsa-sink] alsa-sink.c: in dB: 0: -1,39 dB 1: -1,39 dB Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 0.000) [alsa-sink] sink.c: Volume going down to 22113 at 8308985666 Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 0.000) [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes. Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 0.000) [alsa-sink] alsa-sink.c: Limited to 13320 bytes. Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 0.000) [alsa-sink] alsa-sink.c: before: 3330 Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 0.000) [alsa-sink] alsa-sink.c: after: 3330 Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 0.000) [alsa-sink] alsa-sink.c: Rewound 13320 bytes. Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 0.000) [alsa-sink] sink.c: Processing rewind... Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 0.000) [alsa-sink] sink.c: latency = 12205 Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 0.000) [alsa-sink] sink-input.c: Have to rewind 13320 bytes on render memblockq. Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.443| 0.000) [alsa-sink] source.c: Processing rewind... Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.444| 0.000) [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-0d8c_C-Media_USB_Headphone_Set-00-Set.analog-stereo:analog-output-speaker. Oct 27 20:58:10 caeser pulseaudio[2545]: ( 127.448| 0.003) [alsa-sink] sink.c: Volume change to 22113 at 8308910179 was written 25 usec late Oct 27 20:58:19 caeser pulseaudio[2545]: ( 136.344| 8.896) [pulseaudio] module-device-restore.c: Synced. Oct 27 20:58:20 caeser pulseaudio[2545]: ( 137.747| 1.402) [pulseaudio] client.c: Created 24 "UNIX socket client"