You are not logged in.

#1 2019-05-29 20:39:26

theta
Member
From: India
Registered: 2010-01-16
Posts: 160

Bluetooth: Failed to apply network settings

Hi
This has been a long standing issue on my system. I have been unable to resolve this even after a few updates.
My kernel

uname -a
Linux boolean-pc 4.19.45-1-lts #1 SMP Wed May 22 13:02:41 CEST 2019 x86_64 GNU/Linux

Attached Screenshot of Error:
IgZrNWZ.png

Have tried reinstalling entire bluez 5.50-6.

journalctl o/p on restarting bluetooth service

May 30 02:02:51 boolean-pc bluetoothd[16101]: Bluetooth management interface 1.14 initialized
May 30 02:02:51 boolean-pc systemd[1]: Starting Hostname Service...
May 30 02:02:51 boolean-pc pulseaudio[32460]: E: [pulseaudio] backend-ofono.c: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files
May 30 02:02:52 boolean-pc bluetoothd[16101]: Endpoint registered: sender=:1.77 path=/MediaEndpoint/A2DPSource
May 30 02:02:52 boolean-pc bluetoothd[16101]: Endpoint registered: sender=:1.77 path=/MediaEndpoint/A2DPSink
May 30 02:02:52 boolean-pc dbus-daemon[9594]: [system] Successfully activated service 'org.freedesktop.hostname1'
May 30 02:02:52 boolean-pc systemd[1]: Started Hostname Service.
May 30 02:02:52 boolean-pc audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 30 02:02:52 boolean-pc kernel: audit: type=1130 audit(1559161972.571:116): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 30 02:02:54 boolean-pc sudo[16107]:    theta : TTY=pts/1 ; PWD=/home/theta ; USER=root ; COMMAND=/usr/bin/journalctl -n 20
May 30 02:02:54 boolean-pc sudo[16107]: pam_unix(sudo:session): session opened for user root by (...skipping...
='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 30 02:02:52 boolean-pc kernel: audit: type=1130 audit(1559161972.571:116): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 30 02:02:54 boolean-pc sudo[16107]:    theta : TTY=pts/1 ; PWD=/home/theta ; USER=root ; COMMAND=/usr/bin/journalctl -n 20
May 30 02:02:54 boolean-pc sudo[16107]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 30 02:03:22 boolean-pc systemd[1]: systemd-hostnamed.service: Succeeded.
May 30 02:03:22 boolean-pc audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 30 02:03:22 boolean-pc kernel: audit: type=1131 audit(1559162002.682:117): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 30 02:05:17 boolean-pc sudo[16162]:    theta : TTY=pts/3 ; PWD=/home/theta ; USER=root ; COMMAND=/usr/bin/journalctl -n 20
May 30 02:05:17 boolean-pc sudo[16162]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 30 02:05:55 boolean-pc sudo[16162]: pam_unix(sudo:session): session closed for user root
May 30 02:06:00 boolean-pc sudo[16170]:    theta : TTY=pts/3 ; PWD=/home/theta ; USER=root ; COMMAND=/usr/bin/journalctl -n 20
May 30 02:06:00 boolean-pc sudo[16170]: pam_unix(sudo:session): session opened for user root by (uid=0)
(END)

There is an error w.r.t. [pulseaudio] backend-ofono.c which also I have been unable to resolve. Connecting to bluetooth headsets is extremely difficult.

Looking forward to some solution.

Offline

#2 2019-05-30 10:06:05

V1del
Forum Moderator
Registered: 2012-10-16
Posts: 6,892

Re: Bluetooth: Failed to apply network settings

That's just a error in whatever GUI program you are using here (which one is it supposed to be?) Your journal excerpt shows you have a successful headset connection and you will probably have a sink within pulseaudio/pavucontrol. What's your output for

loginctl session-status
pacmd list-cards
pacmd list-sinks

Offline

#3 2019-05-31 19:08:01

theta
Member
From: India
Registered: 2010-01-16
Posts: 160

Re: Bluetooth: Failed to apply network settings

login session-status:

1 - user (1002)
	   Since: Fri 2019-05-31 17:06:37 IST; 7h ago
	  Leader: 11094 (login)
	    Seat: seat0; vc1
	     TTY: tty1
	 Service: login; type tty; class user
	   State: active
	    Unit: session-1.scope
		  |-  471 /usr/share/skypeforlinux/skypeforlinux --type=renderer --no-sandbox --primordial-pipe-token=35186D602E30188A6E1AF0935BF1C8D9 --lang=en-US --app-path=/usr/share/skypeforlinux/resources/app.asar --node-integration=false --webview-tag=true --no-sandbox --preload=/usr/share/skypeforlinux/resources/app.asar/Preload.js --enable-pinch --num-raster-threads=2 --enable-main-frame-before-activation --content-image-texture-target=0,0,3553;0,1,3553;0,2,3553;0,3,3553;0,4,3553;0,5,3553;0,6,3553;0,7,3553;0,8,3553;0,9,3553;0,10,3553;0,11,3553;0,12,3553;0,13,3553;0,14,3553;0,15,3553;0,16,3553;1,0,3553;1,1,3553;1,2,3553;1,3,3553;1,4,3553;1,5,3553;1,6,3553;1,7,3553;1,8,3553;1,9,3553;1,10,3553;1,11,3553;1,12,3553;1,13,3553;1,14,3553;1,15,3553;1,16,3553;2,0,3553;2,1,3553;2,2,3553;2,3,3553;2,4,3553;2,5,3553;2,6,3553;2,7,3553;2,8,3553;2,9,3553;2,10,3553;2,11,3553;2,12,3553;2,13,3553;2,14,3553;2,15,3553;2,16,3553;3,0,3553;3,1,3553;3,2,3553;3,3,3553;3,4,3553;3,5,3553;3,6,3553;3,7,3553;3,8,
		  |-  511 /usr/lib/firefox/firefox -contentproc -childID 1 -isForBrowser -prefsLen 1 -prefMapSize 191595 -parentBuildID 20190521202118 -greomni /usr/lib/firefox/omni.ja -appomni /usr/lib/firefox/browser/omni.ja -appdir /usr/lib/firefox/browser 32570 true tab
		  |-  654 /usr/lib/firefox/firefox -contentproc -childID 3 -isForBrowser -prefsLen 5808 -prefMapSize 191595 -parentBuildID 20190521202118 -greomni /usr/lib/firefox/omni.ja -appomni /usr/lib/firefox/browser/omni.ja -appdir /usr/lib/firefox/browser 32570 true tab
		  |- 4966 urxvt -name bashrun -title bashrun -geometry 60x1 +sb -background #000000 -foreground #FFFFFF -keysym.Control-Up \033[1;5A -keysym.Control-Down \033[1;5B -keysym.Control-Left \033[1;5D -keysym.Control-Right \033[1;5C -e sh -c /bin/bash --rcfile /usr/share/bashrun/bashrc -i
		  |- 4968 /bin/bash --rcfile /usr/share/bashrun/bashrc -i
		  |- 5188 /usr/lib/firefox/firefox -contentproc -childID 19 -isForBrowser -prefsLen 8869 -prefMapSize 191595 -parentBuildID 20190521202118 -greomni /usr/lib/firefox/omni.ja -appomni /usr/lib/firefox/browser/omni.ja -appdir /usr/lib/firefox/browser 32570 true tab
		  |-11094 login -- theta
		  |-17960 /bin/sh /usr/bin/startx
		  |-19180 xinit /home/theta/.xinitrc -- /home/theta/.xserverrc :0 vt1 -keeptty -auth /tmp/serverauth.8pmIOYDdD5
		  |-19189 /usr/lib/Xorg -nolisten tcp :0 vt1 -keeptty -auth /tmp/serverauth.8pmIOYDdD5 -keeptty
		  |-21552 /usr/lib/firefox/firefox -contentproc -childID 43 -isForBrowser -prefsLen 9002 -prefMapSize 191595 -parentBuildID 20190521202118 -greomni /usr/lib/firefox/omni.ja -appomni /usr/lib/firefox/browser/omni.ja -appdir /usr/lib/firefox/browser 32570 true tab
		  |-22466 /usr/bin/openbox --startup /usr/lib/openbox/openbox-autostart OPENBOX
		  |-22572 /usr/share/ROX-Filer/ROX-Filer /home/theta/Current
		  |-22582 urxvt
		  |-22583 bash
		  |-25720 sh /home/theta/.config/openbox/autostart.sh
		  |-25721 sh /home/theta/.config/openbox/autostart.sh
		  |-25959 mono /usr/lib/tomboy/Tomboy.exe
		  |-26055 /bin/bash /home/theta/.scripts/toggle_wallpaper
		  |-26159 conky
		  |-26258 xscreensaver
		  |-26364 redshift-qt
		  |-26468 pidgin
		  |-26658 /bin/bash /usr/bin/devmon
		  |-27821 tint2
		  |-27822 xcompmgr -c -f
		  |-29090 urxvt
		  |-29091 bash
		  |-29106 sleep 1m
		  |-29107 loginctl session-status
		  |-29457 /usr/bin/udevil --monitor
		  |-32466 /usr/bin/redshift -v
		  |-32492 pasystray
		  |-32493 python /usr/bin/blueman-applet
		  |-32494 /usr/lib/geoclue-2.0/demos/agent
		  |-32501 /usr/share/skypeforlinux/skypeforlinux --executed-from=/home/theta --pid=32491
		  |-32506 /usr/share/skypeforlinux/skypeforlinux --type=zygote --no-sandbox
		  |-32530 /usr/share/skypeforlinux/skypeforlinux --type=gpu-process --no-sandbox --supports-dual-gpus=false --gpu-driver-bug-workarounds=7,24,76 --disable-gl-extensions=GL_KHR_blend_equation_advanced GL_KHR_blend_equation_advanced_coherent --disable-webrtc-hw-encoding --gpu-vendor-id=0x10de --gpu-device-id=0x139a --gpu-driver-vendor --gpu-driver-version --gpu-driver-date --gpu-secondary-vendor-ids=0x8086 --gpu-secondary-device-ids=0x1616 --service-request-channel-token=16599205B100CB212A9574A60E255797
		  `-32570 /usr/lib/firefox/firefox

pacmd list-cards

2 card(s) available.
    index: 0
	name: <alsa_card.pci-0000_00_03.0>
	driver: <module-alsa-card.c>
	owner module: 7
	properties:
		alsa.card = "0"
		alsa.card_name = "HDA Intel HDMI"
		alsa.long_card_name = "HDA Intel HDMI at 0xc4318000 irq 50"
		alsa.driver_name = "snd_hda_intel"
		device.bus_path = "pci-0000:00:03.0"
		sysfs.path = "/devices/pci0000:00/0000:00:03.0/sound/card0"
		device.bus = "pci"
		device.vendor.id = "8086"
		device.vendor.name = "Intel Corporation"
		device.product.id = "160c"
		device.product.name = "Broadwell-U Audio Controller"
		device.form_factor = "internal"
		device.string = "0"
		device.description = "Built-in Audio"
		module-udev-detect.discovered = "1"
		device.icon_name = "audio-card-pci"
	profiles:
		output:hdmi-stereo: Digital Stereo (HDMI) Output (priority 5900, available: no)
		output:hdmi-surround: Digital Surround 5.1 (HDMI) Output (priority 800, available: no)
		output:hdmi-surround71: Digital Surround 7.1 (HDMI) Output (priority 800, available: no)
		output:hdmi-stereo-extra1: Digital Stereo (HDMI 2) Output (priority 5700, available: no)
		output:hdmi-surround-extra1: Digital Surround 5.1 (HDMI 2) Output (priority 600, available: no)
		output:hdmi-surround71-extra1: Digital Surround 7.1 (HDMI 2) Output (priority 600, available: no)
		output:hdmi-stereo-extra2: Digital Stereo (HDMI 3) Output (priority 5700, available: no)
		output:hdmi-surround-extra2: Digital Surround 5.1 (HDMI 3) Output (priority 600, available: no)
		output:hdmi-surround71-extra2: Digital Surround 7.1 (HDMI 3) Output (priority 600, available: no)
		output:hdmi-stereo-extra3: Digital Stereo (HDMI 4) Output (priority 5700, available: no)
		output:hdmi-surround-extra3: Digital Surround 5.1 (HDMI 4) Output (priority 600, available: no)
		output:hdmi-surround71-extra3: Digital Surround 7.1 (HDMI 4) Output (priority 600, available: no)
		output:hdmi-stereo-extra4: Digital Stereo (HDMI 5) Output (priority 5700, available: no)
		output:hdmi-surround-extra4: Digital Surround 5.1 (HDMI 5) Output (priority 600, available: no)
		output:hdmi-surround71-extra4: Digital Surround 7.1 (HDMI 5) Output (priority 600, available: no)
		off: Off (priority 0, available: unknown)
	active profile: <off>
	ports:
		hdmi-output-0: HDMI / DisplayPort (priority 5900, latency offset 0 usec, available: no)
			properties:
				device.icon_name = "video-display"
		hdmi-output-1: HDMI / DisplayPort 2 (priority 5800, latency offset 0 usec, available: no)
			properties:
				device.icon_name = "video-display"
		hdmi-output-2: HDMI / DisplayPort 3 (priority 5700, latency offset 0 usec, available: no)
			properties:
				device.icon_name = "video-display"
		hdmi-output-3: HDMI / DisplayPort 4 (priority 5600, latency offset 0 usec, available: no)
			properties:
				device.icon_name = "video-display"
		hdmi-output-4: HDMI / DisplayPort 5 (priority 5500, latency offset 0 usec, available: no)
			properties:
				device.icon_name = "video-display"
    index: 1
	name: <alsa_card.pci-0000_00_1b.0>
	driver: <module-alsa-card.c>
	owner module: 8
	properties:
		alsa.card = "1"
		alsa.card_name = "HDA Intel PCH"
		alsa.long_card_name = "HDA Intel PCH at 0xc431c000 irq 46"
		alsa.driver_name = "snd_hda_intel"
		device.bus_path = "pci-0000:00:1b.0"
		sysfs.path = "/devices/pci0000:00/0000:00:1b.0/sound/card1"
		device.bus = "pci"
		device.vendor.id = "8086"
		device.vendor.name = "Intel Corporation"
		device.product.id = "9ca0"
		device.product.name = "Wildcat Point-LP High Definition Audio Controller"
		device.form_factor = "internal"
		device.string = "1"
		device.description = "Built-in Audio"
		module-udev-detect.discovered = "1"
		device.icon_name = "audio-card-pci"
	profiles:
		input:analog-stereo: Analog Stereo Input (priority 65, available: unknown)
		output:analog-stereo: Analog Stereo Output (priority 6500, available: unknown)
		output:analog-stereo+input:analog-stereo: Analog Stereo Duplex (priority 6565, available: unknown)
		off: Off (priority 0, available: unknown)
	active profile: <output:analog-stereo+input:analog-stereo>
	sinks:
		alsa_output.pci-0000_00_1b.0.analog-stereo/#0: Built-in Audio Analog Stereo
	sources:
		alsa_output.pci-0000_00_1b.0.analog-stereo.monitor/#0: Monitor of Built-in Audio Analog Stereo
		alsa_input.pci-0000_00_1b.0.analog-stereo/#1: Built-in Audio Analog Stereo
	ports:
		analog-input-internal-mic: Internal Microphone (priority 8900, latency offset 0 usec, available: unknown)
			properties:
				device.icon_name = "audio-input-microphone"
		analog-input-mic: Microphone (priority 8700, latency offset 0 usec, available: no)
			properties:
				device.icon_name = "audio-input-microphone"
		analog-output-speaker: Speakers (priority 10000, latency offset 0 usec, available: no)
			properties:
				device.icon_name = "audio-speakers"
		analog-output-headphones: Headphones (priority 9000, latency offset 0 usec, available: yes)
			properties:
				device.icon_name = "audio-headphones"

pacmd list-sinks

1 sink(s) available.
  * index: 0
	name: <alsa_output.pci-0000_00_1b.0.analog-stereo>
	driver: <module-alsa-card.c>
	flags: HARDWARE HW_MUTE_CTRL HW_VOLUME_CTRL DECIBEL_VOLUME LATENCY DYNAMIC_LATENCY
	state: SUSPENDED
	suspend cause: IDLE
	priority: 9039
	volume: front-left: 79336 / 121% / 4.98 dB,   front-right: 79336 / 121% / 4.98 dB
	        balance 0.00
	base volume: 65536 / 100% / 0.00 dB
	volume steps: 65537
	muted: no
	current latency: 0.00 ms
	max request: 0 KiB
	max rewind: 0 KiB
	monitor source: 0
	sample spec: s16le 2ch 44100Hz
	channel map: front-left,front-right
	             Stereo
	used by: 0
	linked by: 0
	configured latency: 0.00 ms; range is 0.50 .. 2000.00 ms
	card: 1 <alsa_card.pci-0000_00_1b.0>
	module: 8
	properties:
		alsa.resolution_bits = "16"
		device.api = "alsa"
		device.class = "sound"
		alsa.class = "generic"
		alsa.subclass = "generic-mix"
		alsa.name = "ALC3241 Analog"
		alsa.id = "ALC3241 Analog"
		alsa.subdevice = "0"
		alsa.subdevice_name = "subdevice #0"
		alsa.device = "0"
		alsa.card = "1"
		alsa.card_name = "HDA Intel PCH"
		alsa.long_card_name = "HDA Intel PCH at 0xc431c000 irq 46"
		alsa.driver_name = "snd_hda_intel"
		device.bus_path = "pci-0000:00:1b.0"
		sysfs.path = "/devices/pci0000:00/0000:00:1b.0/sound/card1"
		device.bus = "pci"
		device.vendor.id = "8086"
		device.vendor.name = "Intel Corporation"
		device.product.id = "9ca0"
		device.product.name = "Wildcat Point-LP High Definition Audio Controller"
		device.form_factor = "internal"
		device.string = "front:1"
		device.buffering.buffer_size = "352800"
		device.buffering.fragment_size = "176400"
		device.access_mode = "mmap+timer"
		device.profile.name = "analog-stereo"
		device.profile.description = "Analog Stereo"
		device.description = "Built-in Audio Analog Stereo"
		alsa.mixer_name = "Realtek ALC3241"
		alsa.components = "HDA:10ec0290,103c80dc,00100003"
		module-udev-detect.discovered = "1"
		device.icon_name = "audio-card-pci"
	ports:
		analog-output-speaker: Speakers (priority 10000, latency offset 0 usec, available: no)
			properties:
				device.icon_name = "audio-speakers"
		analog-output-headphones: Headphones (priority 9000, latency offset 0 usec, available: yes)
			properties:
				device.icon_name = "audio-headphones"
	active port: <analog-output-headphones>

Offline

#4 2019-06-12 15:50:56

theta
Member
From: India
Registered: 2010-01-16
Posts: 160

Re: Bluetooth: Failed to apply network settings

V1del wrote:

That's just a error in whatever GUI program you are using here (which one is it supposed to be?) Your journal excerpt shows you have a successful headset connection and you will probably have a sink within pulseaudio/pavucontrol. What's your output for

loginctl session-status
pacmd list-cards
pacmd list-sinks

Have got the output but cannot make head or tail of it?

Offline

#5 2019-06-13 08:01:00

V1del
Forum Moderator
Registered: 2012-10-16
Posts: 6,892

Re: Bluetooth: Failed to apply network settings

Sorry this one fell off the radar a bit

Looks generally alright, can you post your .xinitrc? Also general sanity check, pulseaudio-bluetooth is installed? If it is, what output do you get from

systemctl --user mask pulseaudio.socket
systemctl --user stop pulseaudio
pulseaudio -vvv #Keep that running in a terminal, post that output, what happens here if you connect your headset
systemctl --user unmask pulseaudio.socket #To restore pulseaudio autostart

Offline

#6 2019-06-14 08:29:46

theta
Member
From: India
Registered: 2010-01-16
Posts: 160

Re: Bluetooth: Failed to apply network settings

1) systemctl --user mask pulseaudio.socket

Executes sucessfully with no output on terminal

2) systemctl --user stop pulseaudio

Executes sucessfully with no output on terminal

3) pulseaudio -vvv

I: [pulseaudio] sink-input.c:     application.process.host = "boolean-pc"
I: [pulseaudio] sink-input.c:     application.process.binary = "firefox"
I: [pulseaudio] sink-input.c:     application.language = "C"
I: [pulseaudio] sink-input.c:     window.x11.display = ":0"
I: [pulseaudio] sink-input.c:     application.process.machine_id = "5265d46ac6594a469d8e518f56f8743e"
I: [pulseaudio] sink-input.c:     application.process.session_id = "1"
I: [pulseaudio] sink-input.c:     application.icon_name = "firefox"
I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:AudioIPC Server"
I: [pulseaudio] protocol-native.c: Requested tlength=100.00 ms, minreq=25.00 ms
D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
D: [pulseaudio] protocol-native.c: Requested latency=25.00 ms, Received latency=25.00 ms
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=28800, base=8, prebuf=19208, minreq=9600 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=28800, base=8, prebuf=19208, minreq=9600 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 100.00 ms = 25.00 ms + 2*25.00 ms + 25.00 ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Got hardware volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Calculated software volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB (accurate-enough=yes)
D: [alsa-sink-ALC3241 Analog] sink.c: Volume not changing
D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC1 is accessible: yes
D: [pulseaudio] module-udev-detect.c: Resuming all sinks and sources of card alsa_card.pci-0000_00_1b.0.
D: [pulseaudio] protocol-native.c: Client firefox changes volume of sink input AudioStream.
D: [alsa-sink-ALC3241 Analog] sink-input.c: Requesting rewind due to uncorking
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 784 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 196
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 196
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 784 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 1761
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 784 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: IDLE -> RUNNING
D: [alsa-sink-ALC3241 Analog] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 944 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 944 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 1490
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 944 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 944 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] ratelimit.c: 1531 events suppressed
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] protocol-native.c: Implicit underrun of 'AudioStream'
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 48 bytes ago (1152 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 48 bytes ago (1152 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 48 bytes ago (1152 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 368 bytes ago (832 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 688 bytes ago (512 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (192 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink-input.c: Requesting rewind due to corking
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 0 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Mhmm, actually there is nothing to rewind.
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: RUNNING -> IDLE
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Got hardware volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Calculated software volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB (accurate-enough=yes)
D: [alsa-sink-ALC3241 Analog] sink.c: Volume not changing
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 944 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 236
I: [pulseaudio] sink-input.c: Freeing input 11 "AudioStream"
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 944 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 1225
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 944 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
I: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo idle for too long, suspending ...
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Device suspended...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: suspend_cause: (none) -> IDLE
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: IDLE -> SUSPENDED
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: suspend_cause: (none) -> IDLE
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: state: IDLE -> SUSPENDED
I: [pulseaudio] core.c: All sinks and sources are suspended, vacuuming memory
D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC1 is accessible: yes
D: [pulseaudio] module-udev-detect.c: Resuming all sinks and sources of card alsa_card.pci-0000_00_1b.0.
D: [pulseaudio] module-intended-roles.c: Not setting device for stream AudioStream, because it lacks role.
D: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"float32le\""  format.rate = "48000"  format.channels = "2"  format.channel_map = "\"front-left,front-right\""
I: [pulseaudio] sink-input.c: Trying to change sample rate
I: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-application-name:AudioIPC Server.
I: [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-application-name:AudioIPC Server.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
D: [pulseaudio] reserve-wrap.c: Successfully acquired reservation lock on device 'Audio1'
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Trying resume...
I: [alsa-sink-ALC3241 Analog] alsa-util.c: Trying to disable ALSA period wakeups, using timers only
D: [alsa-sink-ALC3241 Analog] alsa-util.c: Maximum hw buffer size is 21845 ms
D: [alsa-sink-ALC3241 Analog] alsa-util.c: Set buffer size first (to 88200 samples), period size second (to 44100 samples).
I: [alsa-sink-ALC3241 Analog] alsa-util.c: ALSA period wakeups disabled
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Time scheduling watermark is 3.12ms
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Resumed successfully...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: suspend_cause: IDLE -> (none)
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Starting playback.
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: SUSPENDED -> IDLE
D: [alsa-sink-ALC3241 Analog] ratelimit.c: 998 events suppressed
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: suspend_cause: IDLE -> (none)
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: state: SUSPENDED -> IDLE
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
D: [pulseaudio] resampler.c: Resampler:
D: [pulseaudio] resampler.c:   rate 48000 -> 48000 (method copy)
D: [pulseaudio] resampler.c:   format float32le -> s16le (intermediate s16le)
D: [pulseaudio] resampler.c:   channels 2 -> 2 (resampling 2)
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
I: [pulseaudio] sink-input.c: Created input 12 "AudioStream" on alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec float32le 2ch 48000Hz and channel map front-left,front-right
I: [pulseaudio] sink-input.c:     media.name = "AudioStream"
I: [pulseaudio] sink-input.c:     application.name = "AudioIPC Server"
I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
I: [pulseaudio] sink-input.c:     native-protocol.version = "32"
I: [pulseaudio] sink-input.c:     application.process.id = "23382"
I: [pulseaudio] sink-input.c:     application.process.user = "theta"
I: [pulseaudio] sink-input.c:     application.process.host = "boolean-pc"
I: [pulseaudio] sink-input.c:     application.process.binary = "firefox"
I: [pulseaudio] sink-input.c:     application.language = "C"
I: [pulseaudio] sink-input.c:     window.x11.display = ":0"
I: [pulseaudio] sink-input.c:     application.process.machine_id = "5265d46ac6594a469d8e518f56f8743e"
I: [pulseaudio] sink-input.c:     application.process.session_id = "1"
I: [pulseaudio] sink-input.c:     application.icon_name = "firefox"
I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:AudioIPC Server"
I: [pulseaudio] protocol-native.c: Requested tlength=100.00 ms, minreq=25.00 ms
D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] protocol-native.c: Requested latency=25.00 ms, Received latency=25.00 ms
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=28800, base=8, prebuf=19208, minreq=9600 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=28800, base=8, prebuf=19208, minreq=9600 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 100.00 ms = 25.00 ms + 2*25.00 ms + 25.00 ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Got hardware volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Calculated software volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB (accurate-enough=yes)
D: [alsa-sink-ALC3241 Analog] sink.c: Volume not changing
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] protocol-native.c: Client firefox changes volume of sink input AudioStream.
D: [alsa-sink-ALC3241 Analog] sink-input.c: Requesting rewind due to uncorking
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 944 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 944 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 1490
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 944 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: IDLE -> RUNNING
D: [alsa-sink-ALC3241 Analog] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 944 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 944 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 2015
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 944 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 944 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] protocol-native.c: Implicit underrun of 'AudioStream'
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 48 bytes ago (992 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 48 bytes ago (992 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 48 bytes ago (992 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 368 bytes ago (832 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 688 bytes ago (512 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (192 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink-input.c: Requesting rewind due to corking
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 0 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Mhmm, actually there is nothing to rewind.
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: RUNNING -> IDLE
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Got hardware volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Calculated software volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB (accurate-enough=yes)
D: [alsa-sink-ALC3241 Analog] sink.c: Volume not changing
I: [pulseaudio] sink-input.c: Freeing input 12 "AudioStream"
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 944 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 944 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 1731
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 944 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
I: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo idle for too long, suspending ...
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Device suspended...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: suspend_cause: (none) -> IDLE
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: IDLE -> SUSPENDED
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: suspend_cause: (none) -> IDLE
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: state: IDLE -> SUSPENDED
I: [pulseaudio] core.c: All sinks and sources are suspended, vacuuming memory
D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC1 is accessible: yes
D: [pulseaudio] module-udev-detect.c: Resuming all sinks and sources of card alsa_card.pci-0000_00_1b.0.
D: [pulseaudio] module-intended-roles.c: Not setting device for stream AudioStream, because it lacks role.
D: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"float32le\""  format.rate = "48000"  format.channels = "2"  format.channel_map = "\"front-left,front-right\""
I: [pulseaudio] sink-input.c: Trying to change sample rate
I: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-application-name:AudioIPC Server.
I: [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-application-name:AudioIPC Server.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
D: [pulseaudio] reserve-wrap.c: Successfully acquired reservation lock on device 'Audio1'
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Trying resume...
I: [alsa-sink-ALC3241 Analog] alsa-util.c: Trying to disable ALSA period wakeups, using timers only
D: [alsa-sink-ALC3241 Analog] alsa-util.c: Maximum hw buffer size is 21845 ms
D: [alsa-sink-ALC3241 Analog] alsa-util.c: Set buffer size first (to 88200 samples), period size second (to 44100 samples).
I: [alsa-sink-ALC3241 Analog] alsa-util.c: ALSA period wakeups disabled
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Time scheduling watermark is 3.12ms
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Resumed successfully...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: suspend_cause: IDLE -> (none)
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: SUSPENDED -> IDLE
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Starting playback.
D: [alsa-sink-ALC3241 Analog] ratelimit.c: 1235 events suppressed
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: suspend_cause: IDLE -> (none)
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: state: SUSPENDED -> IDLE
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
D: [pulseaudio] resampler.c: Resampler:
D: [pulseaudio] resampler.c:   rate 48000 -> 48000 (method copy)
D: [pulseaudio] resampler.c:   format float32le -> s16le (intermediate s16le)
D: [pulseaudio] resampler.c:   channels 2 -> 2 (resampling 2)
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
I: [pulseaudio] sink-input.c: Created input 13 "AudioStream" on alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec float32le 2ch 48000Hz and channel map front-left,front-right
I: [pulseaudio] sink-input.c:     media.name = "AudioStream"
I: [pulseaudio] sink-input.c:     application.name = "AudioIPC Server"
I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
I: [pulseaudio] sink-input.c:     native-protocol.version = "32"
I: [pulseaudio] sink-input.c:     application.process.id = "23382"
I: [pulseaudio] sink-input.c:     application.process.user = "theta"
I: [pulseaudio] sink-input.c:     application.process.host = "boolean-pc"
I: [pulseaudio] sink-input.c:     application.process.binary = "firefox"
I: [pulseaudio] sink-input.c:     application.language = "C"
I: [pulseaudio] sink-input.c:     window.x11.display = ":0"
I: [pulseaudio] sink-input.c:     application.process.machine_id = "5265d46ac6594a469d8e518f56f8743e"
I: [pulseaudio] sink-input.c:     application.process.session_id = "1"
I: [pulseaudio] sink-input.c:     application.icon_name = "firefox"
I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:AudioIPC Server"
I: [pulseaudio] protocol-native.c: Requested tlength=100.00 ms, minreq=25.00 ms
D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] protocol-native.c: Requested latency=25.00 ms, Received latency=25.00 ms
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=28800, base=8, prebuf=19208, minreq=9600 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=28800, base=8, prebuf=19208, minreq=9600 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 100.00 ms = 25.00 ms + 2*25.00 ms + 25.00 ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Got hardware volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Calculated software volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB (accurate-enough=yes)
D: [alsa-sink-ALC3241 Analog] sink.c: Volume not changing
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] protocol-native.c: Client firefox changes volume of sink input AudioStream.
D: [alsa-sink-ALC3241 Analog] sink-input.c: Requesting rewind due to uncorking
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 944 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 944 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 1902
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 944 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: IDLE -> RUNNING
D: [alsa-sink-ALC3241 Analog] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 944 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 944 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 1914
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 944 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 944 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] protocol-native.c: Implicit underrun of 'AudioStream'
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 208 bytes ago (992 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 208 bytes ago (992 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 208 bytes ago (832 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 528 bytes ago (672 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 848 bytes ago (352 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 848 bytes ago (192 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1168 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1168 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink-input.c: Requesting rewind due to corking
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 0 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Mhmm, actually there is nothing to rewind.
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: RUNNING -> IDLE
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Got hardware volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Calculated software volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB (accurate-enough=yes)
D: [alsa-sink-ALC3241 Analog] sink.c: Volume not changing
I: [pulseaudio] sink-input.c: Freeing input 13 "AudioStream"
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 784 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 196
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 196
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 784 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 2490
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 784 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
I: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo idle for too long, suspending ...
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Device suspended...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: suspend_cause: (none) -> IDLE
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: IDLE -> SUSPENDED
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: suspend_cause: (none) -> IDLE
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: state: IDLE -> SUSPENDED
I: [pulseaudio] core.c: All sinks and sources are suspended, vacuuming memory
D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC1 is accessible: yes
D: [pulseaudio] module-udev-detect.c: Resuming all sinks and sources of card alsa_card.pci-0000_00_1b.0.
D: [pulseaudio] module-intended-roles.c: Not setting device for stream AudioStream, because it lacks role.
D: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"float32le\""  format.rate = "48000"  format.channels = "2"  format.channel_map = "\"front-left,front-right\""
I: [pulseaudio] sink-input.c: Trying to change sample rate
I: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-application-name:AudioIPC Server.
I: [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-application-name:AudioIPC Server.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
D: [pulseaudio] reserve-wrap.c: Successfully acquired reservation lock on device 'Audio1'
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Trying resume...
I: [alsa-sink-ALC3241 Analog] alsa-util.c: Trying to disable ALSA period wakeups, using timers only
D: [alsa-sink-ALC3241 Analog] alsa-util.c: Maximum hw buffer size is 21845 ms
D: [alsa-sink-ALC3241 Analog] alsa-util.c: Set buffer size first (to 88200 samples), period size second (to 44100 samples).
I: [alsa-sink-ALC3241 Analog] alsa-util.c: ALSA period wakeups disabled
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Time scheduling watermark is 3.12ms
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Resumed successfully...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: suspend_cause: IDLE -> (none)
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: SUSPENDED -> IDLE
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Starting playback.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [alsa-sink-ALC3241 Analog] ratelimit.c: 1230 events suppressed
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: suspend_cause: IDLE -> (none)
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: state: SUSPENDED -> IDLE
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
D: [pulseaudio] resampler.c: Resampler:
D: [pulseaudio] resampler.c:   rate 48000 -> 48000 (method copy)
D: [pulseaudio] resampler.c:   format float32le -> s16le (intermediate s16le)
D: [pulseaudio] resampler.c:   channels 2 -> 2 (resampling 2)
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
I: [pulseaudio] sink-input.c: Created input 14 "AudioStream" on alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec float32le 2ch 48000Hz and channel map front-left,front-right
I: [pulseaudio] sink-input.c:     media.name = "AudioStream"
I: [pulseaudio] sink-input.c:     application.name = "AudioIPC Server"
I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
I: [pulseaudio] sink-input.c:     native-protocol.version = "32"
I: [pulseaudio] sink-input.c:     application.process.id = "23382"
I: [pulseaudio] sink-input.c:     application.process.user = "theta"
I: [pulseaudio] sink-input.c:     application.process.host = "boolean-pc"
I: [pulseaudio] sink-input.c:     application.process.binary = "firefox"
I: [pulseaudio] sink-input.c:     application.language = "C"
I: [pulseaudio] sink-input.c:     window.x11.display = ":0"
I: [pulseaudio] sink-input.c:     application.process.machine_id = "5265d46ac6594a469d8e518f56f8743e"
I: [pulseaudio] sink-input.c:     application.process.session_id = "1"
I: [pulseaudio] sink-input.c:     application.icon_name = "firefox"
I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:AudioIPC Server"
I: [pulseaudio] protocol-native.c: Requested tlength=100.00 ms, minreq=25.00 ms
D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] protocol-native.c: Requested latency=25.00 ms, Received latency=25.00 ms
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=28800, base=8, prebuf=19208, minreq=9600 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=28800, base=8, prebuf=19208, minreq=9600 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 100.00 ms = 25.00 ms + 2*25.00 ms + 25.00 ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Got hardware volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Calculated software volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB (accurate-enough=yes)
D: [alsa-sink-ALC3241 Analog] sink.c: Volume not changing
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] protocol-native.c: Client firefox changes volume of sink input AudioStream.
D: [alsa-sink-ALC3241 Analog] sink-input.c: Requesting rewind due to uncorking
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 944 bytes.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 944 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 1512
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 944 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: IDLE -> RUNNING
D: [alsa-sink-ALC3241 Analog] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 624 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 156
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 156
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 624 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 2094
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 624 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 624 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] protocol-native.c: Implicit underrun of 'AudioStream'
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 48 bytes ago (1152 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 48 bytes ago (1152 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 48 bytes ago (992 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 368 bytes ago (832 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 688 bytes ago (512 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (192 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink-input.c: Requesting rewind due to corking
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 0 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Mhmm, actually there is nothing to rewind.
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: RUNNING -> IDLE
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Got hardware volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Calculated software volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB (accurate-enough=yes)
D: [alsa-sink-ALC3241 Analog] sink.c: Volume not changing
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 624 bytes.
I: [pulseaudio] sink-input.c: Freeing input 14 "AudioStream"
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 156
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 156
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 624 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 1242
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 624 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
I: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo idle for too long, suspending ...
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Device suspended...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: suspend_cause: (none) -> IDLE
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: IDLE -> SUSPENDED
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: suspend_cause: (none) -> IDLE
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: state: IDLE -> SUSPENDED
I: [pulseaudio] core.c: All sinks and sources are suspended, vacuuming memory
D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC1 is accessible: yes
D: [pulseaudio] module-udev-detect.c: Resuming all sinks and sources of card alsa_card.pci-0000_00_1b.0.
D: [pulseaudio] module-intended-roles.c: Not setting device for stream AudioStream, because it lacks role.
D: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"float32le\""  format.rate = "48000"  format.channels = "2"  format.channel_map = "\"front-left,front-right\""
I: [pulseaudio] sink-input.c: Trying to change sample rate
I: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-application-name:AudioIPC Server.
I: [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-application-name:AudioIPC Server.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
D: [pulseaudio] reserve-wrap.c: Successfully acquired reservation lock on device 'Audio1'
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Trying resume...
I: [alsa-sink-ALC3241 Analog] alsa-util.c: Trying to disable ALSA period wakeups, using timers only
D: [alsa-sink-ALC3241 Analog] alsa-util.c: Maximum hw buffer size is 21845 ms
D: [alsa-sink-ALC3241 Analog] alsa-util.c: Set buffer size first (to 88200 samples), period size second (to 44100 samples).
I: [alsa-sink-ALC3241 Analog] alsa-util.c: ALSA period wakeups disabled
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Time scheduling watermark is 3.12ms
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Resumed successfully...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: suspend_cause: IDLE -> (none)
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: SUSPENDED -> IDLE
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Starting playback.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [alsa-sink-ALC3241 Analog] ratelimit.c: 1239 events suppressed
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: suspend_cause: IDLE -> (none)
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: state: SUSPENDED -> IDLE
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
D: [pulseaudio] resampler.c: Resampler:
D: [pulseaudio] resampler.c:   rate 48000 -> 48000 (method copy)
D: [pulseaudio] resampler.c:   format float32le -> s16le (intermediate s16le)
D: [pulseaudio] resampler.c:   channels 2 -> 2 (resampling 2)
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
I: [pulseaudio] sink-input.c: Created input 15 "AudioStream" on alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec float32le 2ch 48000Hz and channel map front-left,front-right
I: [pulseaudio] sink-input.c:     media.name = "AudioStream"
I: [pulseaudio] sink-input.c:     application.name = "AudioIPC Server"
I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
I: [pulseaudio] sink-input.c:     native-protocol.version = "32"
I: [pulseaudio] sink-input.c:     application.process.id = "23382"
I: [pulseaudio] sink-input.c:     application.process.user = "theta"
I: [pulseaudio] sink-input.c:     application.process.host = "boolean-pc"
I: [pulseaudio] sink-input.c:     application.process.binary = "firefox"
I: [pulseaudio] sink-input.c:     application.language = "C"
I: [pulseaudio] sink-input.c:     window.x11.display = ":0"
I: [pulseaudio] sink-input.c:     application.process.machine_id = "5265d46ac6594a469d8e518f56f8743e"
I: [pulseaudio] sink-input.c:     application.process.session_id = "1"
I: [pulseaudio] sink-input.c:     application.icon_name = "firefox"
I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:AudioIPC Server"
I: [pulseaudio] protocol-native.c: Requested tlength=100.00 ms, minreq=25.00 ms
D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] protocol-native.c: Requested latency=25.00 ms, Received latency=25.00 ms
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=28800, base=8, prebuf=19208, minreq=9600 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=28800, base=8, prebuf=19208, minreq=9600 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 100.00 ms = 25.00 ms + 2*25.00 ms + 25.00 ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Got hardware volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Calculated software volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB (accurate-enough=yes)
D: [alsa-sink-ALC3241 Analog] sink.c: Volume not changing
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] protocol-native.c: Client firefox changes volume of sink input AudioStream.
D: [alsa-sink-ALC3241 Analog] sink-input.c: Requesting rewind due to uncorking
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 944 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 944 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 1799
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 944 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: IDLE -> RUNNING
D: [alsa-sink-ALC3241 Analog] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 784 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 196
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 196
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 784 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 1884
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 784 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 784 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] protocol-native.c: Implicit underrun of 'AudioStream'
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 368 bytes ago (832 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 368 bytes ago (832 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 368 bytes ago (832 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 688 bytes ago (512 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (192 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (192 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1008 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink-input.c: Requesting rewind due to corking
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 0 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Mhmm, actually there is nothing to rewind.
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: RUNNING -> IDLE
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Got hardware volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Calculated software volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB (accurate-enough=yes)
D: [alsa-sink-ALC3241 Analog] sink.c: Volume not changing
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
I: [pulseaudio] sink-input.c: Freeing input 15 "AudioStream"
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 944 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 944 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 1297
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 944 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
I: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo idle for too long, suspending ...
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Device suspended...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: suspend_cause: (none) -> IDLE
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: IDLE -> SUSPENDED
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: suspend_cause: (none) -> IDLE
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: state: IDLE -> SUSPENDED
I: [pulseaudio] core.c: All sinks and sources are suspended, vacuuming memory
D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC1 is accessible: yes
D: [pulseaudio] module-udev-detect.c: Resuming all sinks and sources of card alsa_card.pci-0000_00_1b.0.
D: [pulseaudio] module-intended-roles.c: Not setting device for stream AudioStream, because it lacks role.
D: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"float32le\""  format.rate = "48000"  format.channels = "2"  format.channel_map = "\"front-left,front-right\""
I: [pulseaudio] sink-input.c: Trying to change sample rate
I: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-application-name:AudioIPC Server.
I: [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-application-name:AudioIPC Server.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
D: [pulseaudio] reserve-wrap.c: Successfully acquired reservation lock on device 'Audio1'
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Trying resume...
I: [alsa-sink-ALC3241 Analog] alsa-util.c: Trying to disable ALSA period wakeups, using timers only
D: [alsa-sink-ALC3241 Analog] alsa-util.c: Maximum hw buffer size is 21845 ms
D: [alsa-sink-ALC3241 Analog] alsa-util.c: Set buffer size first (to 88200 samples), period size second (to 44100 samples).
I: [alsa-sink-ALC3241 Analog] alsa-util.c: ALSA period wakeups disabled
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Time scheduling watermark is 3.12ms
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Resumed successfully...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: suspend_cause: IDLE -> (none)
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: SUSPENDED -> IDLE
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Starting playback.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [alsa-sink-ALC3241 Analog] ratelimit.c: 1202 events suppressed
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: suspend_cause: IDLE -> (none)
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: state: SUSPENDED -> IDLE
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
D: [pulseaudio] resampler.c: Resampler:
D: [pulseaudio] resampler.c:   rate 48000 -> 48000 (method copy)
D: [pulseaudio] resampler.c:   format float32le -> s16le (intermediate s16le)
D: [pulseaudio] resampler.c:   channels 2 -> 2 (resampling 2)
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
I: [pulseaudio] sink-input.c: Created input 16 "AudioStream" on alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec float32le 2ch 48000Hz and channel map front-left,front-right
I: [pulseaudio] sink-input.c:     media.name = "AudioStream"
I: [pulseaudio] sink-input.c:     application.name = "AudioIPC Server"
I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
I: [pulseaudio] sink-input.c:     native-protocol.version = "32"
I: [pulseaudio] sink-input.c:     application.process.id = "23382"
I: [pulseaudio] sink-input.c:     application.process.user = "theta"
I: [pulseaudio] sink-input.c:     application.process.host = "boolean-pc"
I: [pulseaudio] sink-input.c:     application.process.binary = "firefox"
I: [pulseaudio] sink-input.c:     application.language = "C"
I: [pulseaudio] sink-input.c:     window.x11.display = ":0"
I: [pulseaudio] sink-input.c:     application.process.machine_id = "5265d46ac6594a469d8e518f56f8743e"
I: [pulseaudio] sink-input.c:     application.process.session_id = "1"
I: [pulseaudio] sink-input.c:     application.icon_name = "firefox"
I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:AudioIPC Server"
I: [pulseaudio] protocol-native.c: Requested tlength=100.00 ms, minreq=25.00 ms
D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] protocol-native.c: Requested latency=25.00 ms, Received latency=25.00 ms
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=28800, base=8, prebuf=19208, minreq=9600 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=28800, base=8, prebuf=19208, minreq=9600 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 100.00 ms = 25.00 ms + 2*25.00 ms + 25.00 ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Got hardware volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Calculated software volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB (accurate-enough=yes)
D: [alsa-sink-ALC3241 Analog] sink.c: Volume not changing
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] protocol-native.c: Client firefox changes volume of sink input AudioStream.
D: [alsa-sink-ALC3241 Analog] sink-input.c: Requesting rewind due to uncorking
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 944 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 236
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 944 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 1683
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 944 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: IDLE -> RUNNING
D: [alsa-sink-ALC3241 Analog] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 784 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 196
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 196
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 784 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 1543
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 784 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 784 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] protocol-native.c: Implicit underrun of 'AudioStream'
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 208 bytes ago (992 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 208 bytes ago (992 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 208 bytes ago (992 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 528 bytes ago (672 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 848 bytes ago (352 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1168 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1168 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1168 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1168 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: Found underrun 1168 bytes ago (32 bytes ahead in playback buffer)
D: [alsa-sink-ALC3241 Analog] sink-input.c: Requesting rewind due to corking
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 0 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Mhmm, actually there is nothing to rewind.
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: RUNNING -> IDLE
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Latency set to 6.25ms
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: hwbuf_unused=351600
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: setting avail_min=88051
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Got hardware volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Calculated software volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB (accurate-enough=yes)
D: [alsa-sink-ALC3241 Analog] sink.c: Volume not changing
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Requested to rewind 1200 bytes.
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Limited to 784 bytes.
I: [pulseaudio] sink-input.c: Freeing input 16 "AudioStream"
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: before: 196
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: after: 196
D: [alsa-sink-ALC3241 Analog] alsa-sink.c: Rewound 784 bytes.
D: [alsa-sink-ALC3241 Analog] sink.c: Processing rewind...
D: [alsa-sink-ALC3241 Analog] sink.c: latency = 546
D: [alsa-sink-ALC3241 Analog] sink-input.c: Have to rewind 784 bytes on render memblockq.
D: [alsa-sink-ALC3241 Analog] source.c: Processing rewind...
I: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo idle for too long, suspending ...
I: [alsa-sink-ALC3241 Analog] alsa-sink.c: Device suspended...
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: suspend_cause: (none) -> IDLE
D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: IDLE -> SUSPENDED
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: suspend_cause: (none) -> IDLE
D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: state: IDLE -> SUSPENDED
I: [pulseaudio] core.c: All sinks and sources are suspended, vacuuming memory
D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC1 is accessible: yes
D: [pulseaudio] module-udev-detect.c: Resuming all sinks and sources of card alsa_card.pci-0000_00_1b.0.

4)
$ systemctl --user unmask pulseaudio.socket
Removed /home/user/.config/systemd/user/pulseaudio.socket.

Offline

#7 2019-06-14 08:54:48

V1del
Forum Moderator
Registered: 2012-10-16
Posts: 6,892

Re: Bluetooth: Failed to apply network settings

That is truncated, and the pulse module either not loaded or not detected... Work through

https://wiki.archlinux.org/index.php/Bl … g_does_not
https://wiki.archlinux.org/index.php/Bl … play_sound

Come back with any error messages or uncertainties.

Offline

#8 2019-06-16 17:41:30

theta
Member
From: India
Registered: 2010-01-16
Posts: 160

Re: Bluetooth: Failed to apply network settings

Okay a couple of common errors. Now I can't even seem to pair with headset or speakers both. The bluetooth modules are not loading at all.

$ sudo pactl load-module module-bluetooth-discover
Failure: Module initialization failed
$ sudo pactl load-module module-bluetooth-policy  
Failure: Module initialization failed
$ sudo pactl load-module module-bluez5-device   
Failure: Module initialization failed
$ sudo pactl load-module module-bluez5-discover
Failure: Module initialization failed

Offline

#9 2019-06-16 17:45:47

theta
Member
From: India
Registered: 2010-01-16
Posts: 160

Re: Bluetooth: Failed to apply network settings

I found this in my startup log:

[ 2355.274176] Bluetooth: hci0: unexpected event for opcode 0xfc2f

Offline

#10 2019-06-18 12:01:17

V1del
Forum Moderator
Registered: 2012-10-16
Posts: 6,892

Re: Bluetooth: Failed to apply network settings

hmm... There are some known bluetooth issues in the kernel according to https://cdn.kernel.org/pub/linux/kernel … Log-5.1.10 there should be a fix for those, the 5.1.11 kernel is currently in testing. core

Last edited by V1del (2019-06-18 13:17:29)

Offline

#11 2019-06-18 13:11:16

theta
Member
From: India
Registered: 2010-01-16
Posts: 160

Re: Bluetooth: Failed to apply network settings

V1del wrote:

hmm... There are some known bluetooth issues in the kernel according to https://cdn.kernel.org/pub/linux/kernel … Log-5.1.10 there should be a fix for those, the 5.1.11 kernel is currently in testing.

Anything I can help the kernel guys with?

Offline

#12 2019-06-18 13:18:49

V1del
Forum Moderator
Registered: 2012-10-16
Posts: 6,892

Re: Bluetooth: Failed to apply network settings

Make sure your system is updated, the relevant commit I'm thinking of is fixed in the latest versions. Then recheck dmesg/try to get a connection up again.

Offline

#13 2019-06-24 18:43:23

theta
Member
From: India
Registered: 2010-01-16
Posts: 160

Re: Bluetooth: Failed to apply network settings

I use the LTS kernel, just updating to 4.19.55. Will recheck and post relevant O/P.

Offline

#14 2019-07-14 09:18:32

theta
Member
From: India
Registered: 2010-01-16
Posts: 160

Re: Bluetooth: Failed to apply network settings

Post update of the kernel (LTS only). I have installed the normal one also but it requires a number of rebuilding of modules, will try that out subsequently.
Log file errors are listed below

journalctl -p 3 -xb

Jul 14 10:21:12 boolean-pc kernel: ACPI BIOS Error (bug): Could not resolve [\_SB.PCI0.LPCB.HEC.>
Jul 14 10:21:12 boolean-pc kernel: ACPI Error: Method parse/execution failed \_TZ.FN00._ON, AE_N>
Jul 14 10:21:12 boolean-pc kernel: ACPI BIOS Error (bug): Could not resolve [\_SB.PCI0.LPCB.HEC.>
Jul 14 10:21:12 boolean-pc kernel: ACPI Error: Method parse/execution failed \_TZ.FN00._ON, AE_N>
Jul 14 10:21:12 boolean-pc kernel: acpi PNP0C0B:00: Failed to set initial power state
Jul 14 10:21:12 boolean-pc kernel: ACPI BIOS Error (bug): Could not resolve [\_SB.PCI0.LPCB.HEC.>
Jul 14 10:21:12 boolean-pc kernel: ACPI Error: Method parse/execution failed \_TZ.TZ00._TMP, AE_>
Jul 14 10:21:12 boolean-pc kernel: ACPI BIOS Error (bug): Could not resolve [\_SB.PCI0.LPCB.HEC.>
Jul 14 10:21:12 boolean-pc kernel: ACPI Error: Method parse/execution failed \_TZ.TZ00._TMP, AE_>
Jul 14 10:21:12 boolean-pc kernel: ACPI BIOS Error (bug): Could not resolve [\_SB.PCI0.LPCB.HEC.>
Jul 14 10:21:12 boolean-pc kernel: ACPI Error: Method parse/execution failed \_TZ.TZ01._TMP, AE_>
Jul 14 10:21:12 boolean-pc kernel: ACPI BIOS Error (bug): Could not resolve [\_SB.PCI0.LPCB.HEC.>
Jul 14 10:21:12 boolean-pc kernel: ACPI Error: Method parse/execution failed \_TZ.TZ01._TMP, AE_>
Jul 14 10:21:15 boolean-pc kernel: kvm: disabled by bios
Jul 14 10:21:24 boolean-pc kernel: Bluetooth: hci0: unexpected event for opcode 0xfc2f
Jul 14 10:21:24 boolean-pc kernel: print_req_error: critical medium error, dev sr0, sector 16368
Jul 14 10:21:24 boolean-pc kernel: Buffer I/O error on dev sr0, logical block 2046, async page r>
Jul 14 10:21:24 boolean-pc kernel: print_req_error: critical medium error, dev sr0, sector 16360
Jul 14 10:21:24 boolean-pc kernel: Buffer I/O error on dev sr0, logical block 2045, async page r>
Jul 14 10:21:24 boolean-pc kernel: print_req_error: critical medium error, dev sr0, sector 16344
Jul 14 10:21:24 boolean-pc kernel: Buffer I/O error on dev sr0, logical block 2043, async page r>
Jul 14 10:21:24 boolean-pc kernel: print_req_error: critical medium error, dev sr0, sector 16336
Jul 14 10:21:24 boolean-pc kernel: Buffer I/O error on dev sr0, logical block 2042, async page r>
Jul 14 13:20:21 boolean-pc kernel: ACPI Error: Cannot assign type [Reference] to [RegionField] 

It shows a bluetooth opcode 0xfc2f

The errors with ACPI have also been there for a long time. I haven't had the time to correct them. Any suggestions?

Offline

#15 2019-07-14 15:04:13

ewaller
Administrator
From: Pasadena, CA
Registered: 2009-07-13
Posts: 16,607

Re: Bluetooth: Failed to apply network settings

The main line kernel is now 5.2 on Arch; it has had significant firmware updates.   You might try your luck with it.


Nothing is too wonderful to be true, if it be consistent with the laws of nature -- Michael Faraday
Sometimes it is the people no one can imagine anything of who do the things no one can imagine. -- Alan Turing
---
How to Ask Questions the Smart Way

Online

Board footer

Powered by FluxBB