Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Turning on application sets volume to 100 #346

Closed
relh opened this issue Sep 27, 2018 · 15 comments
Closed

Turning on application sets volume to 100 #346

relh opened this issue Sep 27, 2018 · 15 comments

Comments

@relh
Copy link

relh commented Sep 27, 2018

When I click "ON" for any application, the slider controlling volume for that application (all applications?) is immediately set to 100, regardless of its current position. My system headphone volume also jumps to 100 (these are linked/match to each other the entire time).

I am on Ubuntu 18.04. I think this bug could be quite dangerous to people who are not treating audio adjusting applications with appropriate caution. I love PulseEffects, thank you to all contributors!

  1. Ubuntu 18.04 GNOME
  2. Native Package
  3. (pulseeffects:3228): pulseeffects-DEBUG: 09:34:50.303: application: destroyed
Logs
4.
0	module-device-restore		
1	module-stream-restore		
2	module-card-restore		
3	module-augment-properties		
4	module-switch-on-port-available		
5	module-switch-on-connect		
6	module-udev-detect		
7	module-alsa-card	device_id="0" name="pci-0000_00_1f.3" card_name="alsa_card.pci-0000_00_1f.3" namereg_fail=false tsched=yes fixed_latency_range=no ignore_dB=no deferred_volume=yes use_ucm=yes card_properties="module-udev-detect.discovered=1"	
8	module-bluetooth-policy		
9	module-bluetooth-discover		
10	module-bluez5-discover		
11	module-native-protocol-unix		
12	module-default-device-restore		
13	module-rescue-streams		
14	module-always-sink		
15	module-intended-roles		
16	module-suspend-on-idle		
17	module-console-kit		
18	module-systemd-login		
19	module-position-event-sounds		
20	module-role-cork		
21	module-snap-policy		
22	module-filter-heuristics		
23	module-filter-apply		
24	module-x11-publish	display=:0	
25	module-x11-bell	display=:0 sample=bell.ogg	
26	module-x11-cork-request	display=:0	
27	module-x11-xsmp	display=:0 session_manager=local/deck:@/tmp/.ICE-unix/1848,unix/deck:/tmp/.ICE-unix/1848	
28	module-null-sink	sink_name=PulseEffects_apps sink_properties=device.description="PulseEffects(apps)"device.class="sound" channels=2 rate=48000	
29	module-null-sink	sink_name=PulseEffects_mic sink_properties=device.description="PulseEffects(mic)"device.class="sound" channels=2 rate=48000	
1	PulseEffects_apps	module-null-sink.c	float32le 2ch 48000Hz	SUSPENDED
2	PulseEffects_mic	module-null-sink.c	float32le 2ch 48000Hz	SUSPENDED
6	alsa_output.pci-0000_00_1f.3.analog-stereo	module-alsa-card.c	s32le 2ch 48000Hz	RUNNING
1	alsa_input.pci-0000_00_1f.3.analog-stereo	module-alsa-card.c	s32le 2ch 48000Hz	SUSPENDED
2	PulseEffects_apps.monitor	module-null-sink.c	float32le 2ch 48000Hz	SUSPENDED
3	PulseEffects_mic.monitor	module-null-sink.c	float32le 2ch 48000Hz	SUSPENDED
7	alsa_output.pci-0000_00_1f.3.analog-stereo.monitor	module-alsa-card.c	s32le 2ch 48000Hz	IDLE
905	6	442	protocol-native.c	float32le 2ch 48000Hz
0	module-systemd-login.c	(null)
1	protocol-native.c	gnome-shell
2	protocol-native.c	gnome-shell
8	module-x11-xsmp.c	(null)
9	protocol-native.c	gsd-media-keys
12	protocol-native.c	chrome
13	protocol-native.c	Google Play Music Desktop Player
14	protocol-native.c	gsd-media-keys
33	protocol-native.c	gsd-power
117	protocol-native.c	gsd-media-keys
425	protocol-native.c	pulseeffects
442	protocol-native.c	Google Play Music Desktop Player
465	protocol-native.c	pactl
0	bell.ogg	float32le 1ch 44100Hz	0.200
1	audio-volume-change	s16le 2ch 44100Hz	0.067
2	bell-window-system	s16le 1ch 44100Hz	0.200
3	screen-capture	s16le 2ch 96000Hz	0.872
0	alsa_card.pci-0000_00_1f.3	module-alsa-card.c
5.
3 sink(s) available.
    index: 1
	name: <PulseEffects_apps>
	driver: <module-null-sink.c>
	flags: DECIBEL_VOLUME LATENCY FLAT_VOLUME DYNAMIC_LATENCY
	state: SUSPENDED
	suspend cause: IDLE
	priority: 9000
	volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
	        balance 0.00
	base volume: 65536 / 100% / 0.00 dB
	volume steps: 65537
	muted: no
	current latency: 0.00 ms
	max request: 750 KiB
	max rewind: 750 KiB
	monitor source: 2
	sample spec: float32le 2ch 48000Hz
	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
	module: 28
	properties:
		device.description = "PulseEffects(apps)"
		device.class = "sound"
		device.icon_name = "audio-card"
    index: 2
	name: <PulseEffects_mic>
	driver: <module-null-sink.c>
	flags: DECIBEL_VOLUME LATENCY FLAT_VOLUME DYNAMIC_LATENCY
	state: SUSPENDED
	suspend cause: IDLE
	priority: 9000
	volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
	        balance 0.00
	base volume: 65536 / 100% / 0.00 dB
	volume steps: 65537
	muted: no
	current latency: 0.00 ms
	max request: 750 KiB
	max rewind: 750 KiB
	monitor source: 3
	sample spec: float32le 2ch 48000Hz
	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
	module: 29
	properties:
		device.description = "PulseEffects(mic)"
		device.class = "sound"
		device.icon_name = "audio-card"
  * index: 6
	name: <alsa_output.pci-0000_00_1f.3.analog-stereo>
	driver: <module-alsa-card.c>
	flags: HARDWARE HW_MUTE_CTRL HW_VOLUME_CTRL DECIBEL_VOLUME LATENCY FLAT_VOLUME DYNAMIC_LATENCY
	state: RUNNING
	suspend cause: (none)
	priority: 9039
	volume: front-left: 18352 /  28% / -33.17 dB,   front-right: 18352 /  28% / -33.17 dB
	        balance 0.00
	base volume: 65536 / 100% / 0.00 dB
	volume steps: 65537
	muted: no
	current latency: 21.47 ms
	max request: 8 KiB
	max rewind: 8 KiB
	monitor source: 7
	sample spec: s32le 2ch 48000Hz
	channel map: front-left,front-right
	             Stereo
	used by: 1
	linked by: 1
	configured latency: 21.33 ms; range is 2.00 .. 170.67 ms
	card: 0 <alsa_card.pci-0000_00_1f.3>
	module: 7
	properties:
		alsa.resolution_bits = "32"
		device.api = "alsa"
		device.class = "sound"
		alsa.class = "generic"
		alsa.subclass = "generic-mix"
		alsa.name = "ALC3246 Analog"
		alsa.id = "ALC3246 Analog"
		alsa.subdevice = "0"
		alsa.subdevice_name = "subdevice #0"
		alsa.device = "0"
		alsa.card = "0"
		alsa.card_name = "HDA Intel PCH"
		alsa.long_card_name = "HDA Intel PCH at 0xdc728000 irq 131"
		alsa.driver_name = "snd_hda_intel"
		device.bus_path = "pci-0000:00:1f.3"
		sysfs.path = "/devices/pci0000:00/0000:00:1f.3/sound/card0"
		device.bus = "pci"
		device.vendor.id = "8086"
		device.vendor.name = "Intel Corporation"
		device.product.id = "9d70"
		device.product.name = "Sunrise Point-LP HD Audio"
		device.form_factor = "internal"
		device.string = "front:0"
		device.buffering.buffer_size = "65536"
		device.buffering.fragment_size = "32768"
		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 ALC3246"
		alsa.components = "HDA:10ec0256,10280704,00100002 HDA:80862809,80860101,00100000"
		module-udev-detect.discovered = "1"
		device.icon_name = "audio-card-pci"
	ports:
		analog-output-speaker: Speakers (priority 10000, latency offset 0 usec, available: unknown)
			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>
6.
1 sink input(s) available.
    index: 905
	driver: <protocol-native.c>
	flags: START_CORKED 
	state: RUNNING
	sink: 6 <alsa_output.pci-0000_00_1f.3.analog-stereo>
	volume: front-left: 18352 /  28% / -33.17 dB,   front-right: 18352 /  28% / -33.17 dB
	        balance 0.00
	muted: no
	current latency: 40.58 ms
	requested latency: 21.33 ms
	sample spec: float32le 2ch 48000Hz
	channel map: front-left,front-right
	             Stereo
	resample method: copy
	module: 11
	client: 442 <Google Play Music Desktop Player>
	properties:
		application.icon_name = "chromium-browser"
		media.name = "Playback"
		application.name = "Google Play Music Desktop Player"
		native-protocol.peer = "UNIX socket client"
		native-protocol.version = "32"
		application.process.id = "3279"
		application.process.user = "relh"
		application.process.host = "deck"
		application.process.binary = "Google Play Music Desktop Player"
		application.language = "en_US.UTF-8"
		window.x11.display = ":0"
		application.process.machine_id = "63742bc716724c1eb0f834038e2d8a1f"
		application.process.session_id = "2"
		module-stream-restore.id = "sink-input-by-application-name:Google Play Music Desktop Player"
7.
4 source(s) available.
  * index: 1
	name: <alsa_input.pci-0000_00_1f.3.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: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
	        balance 0.00
	base volume: 6554 /  10% / -60.00 dB
	volume steps: 65537
	muted: yes
	current latency: 0.00 ms
	max rewind: 0 KiB
	sample spec: s32le 2ch 48000Hz
	channel map: front-left,front-right
	             Stereo
	used by: 0
	linked by: 0
	configured latency: 0.00 ms; range is 0.50 .. 170.67 ms
	card: 0 <alsa_card.pci-0000_00_1f.3>
	module: 7
	properties:
		alsa.resolution_bits = "32"
		device.api = "alsa"
		device.class = "sound"
		alsa.class = "generic"
		alsa.subclass = "generic-mix"
		alsa.name = "ALC3246 Analog"
		alsa.id = "ALC3246 Analog"
		alsa.subdevice = "0"
		alsa.subdevice_name = "subdevice #0"
		alsa.device = "0"
		alsa.card = "0"
		alsa.card_name = "HDA Intel PCH"
		alsa.long_card_name = "HDA Intel PCH at 0xdc728000 irq 131"
		alsa.driver_name = "snd_hda_intel"
		device.bus_path = "pci-0000:00:1f.3"
		sysfs.path = "/devices/pci0000:00/0000:00:1f.3/sound/card0"
		device.bus = "pci"
		device.vendor.id = "8086"
		device.vendor.name = "Intel Corporation"
		device.product.id = "9d70"
		device.product.name = "Sunrise Point-LP HD Audio"
		device.form_factor = "internal"
		device.string = "front:0"
		device.buffering.buffer_size = "65536"
		device.buffering.fragment_size = "32768"
		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 ALC3246"
		alsa.components = "HDA:10ec0256,10280704,00100002 HDA:80862809,80860101,00100000"
		module-udev-detect.discovered = "1"
		device.icon_name = "audio-card-pci"
	ports:
		analog-input-internal-mic: Internal Microphone (priority 8900, latency offset 0 usec, available: unknown)
			properties:
				device.icon_name = "audio-input-microphone"
		analog-input-headphone-mic: Microphone (priority 8700, latency offset 0 usec, available: unknown)
			properties:
				device.icon_name = "audio-input-microphone"
		analog-input-headset-mic: Headset Microphone (priority 8800, latency offset 0 usec, available: unknown)
			properties:
				device.icon_name = "audio-input-microphone"
	active port: <analog-input-headset-mic>
    index: 2
	name: <PulseEffects_apps.monitor>
	driver: <module-null-sink.c>
	flags: DECIBEL_VOLUME LATENCY DYNAMIC_LATENCY
	state: SUSPENDED
	suspend cause: IDLE
	priority: 1000
	volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
	        balance 0.00
	base volume: 65536 / 100% / 0.00 dB
	volume steps: 65537
	muted: no
	current latency: 0.00 ms
	max rewind: 750 KiB
	sample spec: float32le 2ch 48000Hz
	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
	monitor_of: 1
	module: 28
	properties:
		device.description = "Monitor of PulseEffects(apps)"
		device.class = "monitor"
		device.icon_name = "audio-input-microphone"
    index: 3
	name: <PulseEffects_mic.monitor>
	driver: <module-null-sink.c>
	flags: DECIBEL_VOLUME LATENCY DYNAMIC_LATENCY
	state: SUSPENDED
	suspend cause: IDLE
	priority: 1000
	volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
	        balance 0.00
	base volume: 65536 / 100% / 0.00 dB
	volume steps: 65537
	muted: no
	current latency: 0.00 ms
	max rewind: 750 KiB
	sample spec: float32le 2ch 48000Hz
	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
	monitor_of: 2
	module: 29
	properties:
		device.description = "Monitor of PulseEffects(mic)"
		device.class = "monitor"
		device.icon_name = "audio-input-microphone"
    index: 7
	name: <alsa_output.pci-0000_00_1f.3.analog-stereo.monitor>
	driver: <module-alsa-card.c>
	flags: DECIBEL_VOLUME LATENCY DYNAMIC_LATENCY
	state: IDLE
	suspend cause: (none)
	priority: 1030
	volume: front-left: 65536 / 100% / 0.00 dB,   front-right: 65536 / 100% / 0.00 dB
	        balance 0.00
	base volume: 65536 / 100% / 0.00 dB
	volume steps: 65537
	muted: no
	current latency: 0.00 ms
	max rewind: 8 KiB
	sample spec: s32le 2ch 48000Hz
	channel map: front-left,front-right
	             Stereo
	used by: 0
	linked by: 0
	configured latency: 170.67 ms; range is 2.00 .. 170.67 ms
	monitor_of: 6
	card: 0 <alsa_card.pci-0000_00_1f.3>
	module: 7
	properties:
		device.description = "Monitor of Built-in Audio Analog Stereo"
		device.class = "monitor"
		alsa.card = "0"
		alsa.card_name = "HDA Intel PCH"
		alsa.long_card_name = "HDA Intel PCH at 0xdc728000 irq 131"
		alsa.driver_name = "snd_hda_intel"
		device.bus_path = "pci-0000:00:1f.3"
		sysfs.path = "/devices/pci0000:00/0000:00:1f.3/sound/card0"
		device.bus = "pci"
		device.vendor.id = "8086"
		device.vendor.name = "Intel Corporation"
		device.product.id = "9d70"
		device.product.name = "Sunrise Point-LP HD Audio"
		device.form_factor = "internal"
		device.string = "0"
		module-udev-detect.discovered = "1"
		device.icon_name = "audio-card-pci"
8.
0 source output(s) available.
9.
None
10.
[/]
enable-all-apps=true
spectrum-color=[1.0, 1.0, 1.0, 1.0]
use-custom-color=false
last-used-preset='Bass Enhancing + Perfect EQ'
spectrum-n-points=150
use-dark-theme=true
version='4.3.5'
show-spectrum=true
spectrum-height=100

[sinkinputs/equalizer]
band0-width=19.905246299377595
band0-gain=4.0
band4-type='peak'
band5-gain=-2.0
band1-width=39.716187811321859
band2-width=79.244212834156855
band3-width=158.11299154736642
band7-frequency=4000.0
band5-frequency=1000.0
band4-width=315.47689354145319
band1-frequency=64.0
band4-gain=-1.0
band3-type='peak'
band9-type='peak'
band3-frequency=250.0
band5-width=629.45915692671076
band6-width=1255.9361346279484
band2-type='peak'
band3-gain=0.0
band7-width=2505.9220394308272
band9-gain=3.0
band8-type='peak'
band7-type='peak'
band9-frequency=16000.0
band8-width=4999.9718095262879
output-gain=0.0
band1-type='peak'
band2-gain=1.0
band9-width=9976.2553274545608
band8-gain=3.0
band8-frequency=8000.0
band6-frequency=2000.0
band4-frequency=500.0
band2-frequency=125.0
band7-gain=2.0
band6-type='peak'
band0-type='peak'
band1-gain=2.0
band0-frequency=32.0
state=true
installed=true
band5-type='peak'
band6-gain=0.0
input-gain=0.0
num-bands=10
post-messages=true

[sinkinputs/limiter]
lookahead=5.0
input-gain=0.0
oversampling=1
post-messages=true
limit=0.0
asc-level=0.5
release=50.0
asc=false
installed=true
state=false

[sinkinputs/loudness]
loudness=-3.1000000000000001
output=-6.0
link=-9.0999999999999996
post-messages=true
state=false
installed=true

[sinkinputs/filter]
frequency=2000.0
resonance=-3.0
inertia=20.0
mode='12dB/oct Lowpass'
input-gain=0.0
post-messages=true
output-gain=0.0
installed=true
state=false

[sinkinputs/exciter]
ceil=16000.0
blend=0.0
harmonics=8.5
amount=0.0
input-gain=0.0
post-messages=true
listen=false
ceil-active=false
output-gain=0.0
installed=true
state=false
scope=7500.0

[sinkinputs/maximizer]
post-messages=true
release=3.1600000000000001
ceiling=0.0
state=false
installed=true
threshold=0.0

[sinkinputs/autogain]
target=-23.0
input-gain=0.0
weight-i=1
post-messages=true
weight-m=1
output-gain=0.0
weight-s=1
installed=true
state=false

[sinkinputs]
plugins=['limiter', 'autogain', 'gate', 'multiband_gate', 'compressor', 'multiband_compressor', 'convolver', 'bass_enhancer', 'exciter', 'crystalizer', 'stereo_tools', 'reverb', 'equalizer', 'deesser', 'crossfeed', 'loudness', 'maximizer', 'filter']

[sinkinputs/reverb]
predelay=0.0
room-size='Large'
decay-time=1.5
amount=-12.0
input-gain=0.0
diffusion=0.5
bass-cut=300.0
post-messages=true
treble-cut=5000.0
hf-damp=5000.0
output-gain=0.0
installed=true
dry=0.0
state=false

[sinkinputs/crossfeed]
fcut=700
post-messages=true
state=false
feed=4.5
installed=true

[sinkinputs/gate]
range=-24.0
detection='RMS'
stereo-link='Average'
threshold=-18.0
ratio=2.0
post-messages=true
makeup=0.0
attack=20.0
knee=9.0
installed=true
state=false
release=250.0

[sinkinputs/multibandcompressor]
mode='LR8'
makeup1=0.0
makeup2=0.0
attack0=150.0
attack1=150.0
bypass2=false
attack3=150.0
solo2=false
attack2=150.0
solo1=false
makeup0=0.0
knee0=9.0
threshold0=-12.0
threshold1=-12.0
solo3=false
threshold3=-12.0
freq0=120.0
freq1=1000.0
freq2=6000.0
threshold2=-12.0
solo0=false
state=false
release0=300.0
ratio0=2.0
ratio1=2.0
output-gain=0.0
ratio2=2.0
ratio3=2.0
post-messages=true
release1=300.0
release2=300.0
release3=300.0
input-gain=0.0
bypass0=false
bypass1=false
detection0='RMS'
detection1='RMS'
knee1=9.0
detection2='RMS'
detection3='RMS'
knee3=9.0
knee2=9.0
bypass3=false
installed=true
makeup3=0.0

[sinkinputs/compressor]
detection='RMS'
stereo-link='Average'
mix=0.0
threshold=-18.0
ratio=2.0
post-messages=true
makeup=0.0
attack=20.0
knee=9.0
installed=true
state=false
release=250.0

[sinkinputs/bassenhancer]
blend=0.0
harmonics=8.5
floor-active=false
amount=0.0
input-gain=0.0
post-messages=true
listen=false
output-gain=0.0
installed=true
state=true
floor=20.0
scope=100.0

[sinkinputs/multibandgate]
mode='LR8'
makeup1=0.0
makeup2=0.0
attack0=150.0
attack1=150.0
bypass2=false
attack3=150.0
solo2=false
attack2=150.0
solo1=false
makeup0=0.0
knee0=9.0
threshold0=-12.0
threshold1=-12.0
solo3=false
threshold3=-12.0
freq0=120.0
freq1=1000.0
freq2=6000.0
threshold2=-12.0
solo0=false
state=false
release0=300.0
ratio0=2.0
ratio1=2.0
output-gain=0.0
ratio2=2.0
ratio3=2.0
post-messages=true
range0=-24.0
range1=-24.0
range2=-24.0
range3=-24.0
release1=300.0
release2=300.0
release3=300.0
input-gain=0.0
bypass0=false
bypass1=false
detection0='RMS'
detection1='RMS'
knee1=9.0
detection2='RMS'
detection3='RMS'
knee3=9.0
knee2=9.0
bypass3=false
installed=true
makeup3=0.0

[sinkinputs/deesser]
f1-freq=6000.0
f2-freq=4500.0
detection='RMS'
mode='Wide'
threshold=-18.0
ratio=3.0
f2-q=1.0
f2-level=12.0
post-messages=true
f1-level=0.0
makeup=0.0
sc-listen=false
laxity=15
installed=true
state=false

[sinkinputs/convolver]
post-messages=true
kernel-path=''
state=false
input-gain=0.0
output-gain=0.0
installed=true
ir-width=100

[sinkinputs/stereotools]
delay=0.0
slev=0.0
sbal=0.0
mode='LR > LR (Stereo Default)'
input-gain=0.0
phasel=false
stereo-phase=0.0
post-messages=true
mlev=0.0
mpan=0.0
phaser=false
balance-in=0.0
mutel=false
output-gain=0.0
softclip=false
installed=true
state=false
balance-out=0.0
muter=false
sc-level=1.0
stereo-base=0.0

[sinkinputs/crystalizer]
post-messages=true
state=true
input-gain=0.0
intensity=1.0
installed=true
output-gain=0.0

[sourceoutputs/pitch]
post-messages=true
crispness=3
faster=false
installed=true
cents=0.0
formant-preserving=false
state=false
semitones=0
octaves=0

[sourceoutputs/equalizer]
band23-frequency=4507.1499999999996
band3-gain=0.0
band7-frequency=113.20999999999999
band8-gain=0.0
band26-width=2061.5999999999999
band12-width=82.069999999999993
band24-gain=0.0
band19-width=411.33999999999997
band25-frequency=7143.3500000000004
band9-frequency=179.43000000000001
band27-frequency=11321.450000000001
band19-type='peak'
band10-width=51.789999999999999
band16-gain=0.0
band26-type='peak'
band5-type='peak'
band2-gain=0.0
band23-gain=0.0
band29-frequency=17943.279999999999
band0-frequency=22.59
state=false
band2-frequency=35.799999999999997
band18-type='peak'
band15-gain=0.0
band4-type='peak'
band25-type='peak'
band22-gain=0.0
band4-frequency=56.740000000000002
band9-type='peak'
band1-gain=0.0
band5-width=16.379999999999999
band6-frequency=89.930000000000007
band8-frequency=142.53
band3-width=10.33
band17-type='peak'
band14-gain=0.0
band3-type='peak'
band24-type='peak'
band0-gain=0.0
band21-gain=0.0
band11-width=65.189999999999998
num-bands=30
band8-type='peak'
band18-width=326.74000000000001
band25-width=1637.5899999999999
band23-width=1033.25
band16-type='peak'
band16-width=206.16
band13-gain=0.0
band23-type='peak'
band2-type='peak'
band20-gain=0.0
band16-frequency=899.28999999999996
band15-type='peak'
band12-gain=0.0
band22-type='peak'
band1-type='peak'
band18-frequency=1425.29
band29-gain=0.0
post-messages=true
band2-width=8.2100000000000009
band9-width=41.130000000000003
band14-type='peak'
band11-gain=0.0
band0-type='peak'
band21-type='peak'
band28-gain=0.0
band17-width=259.54000000000002
band24-width=1300.78
band11-frequency=284.38
output-gain=0.0
band7-gain=0.0
band0-width=5.1799999999999997
band7-width=25.949999999999999
band13-frequency=450.72000000000003
band22-width=820.74000000000001
band29-width=4113.4399999999996
band15-width=163.75999999999999
band13-type='peak'
band10-gain=0.0
band15-frequency=714.34000000000003
band20-type='peak'
band6-gain=0.0
band20-frequency=2258.9299999999998
band27-gain=0.0
band20-width=517.85000000000002
band17-frequency=1132.1500000000001
band27-width=2595.4000000000001
band13-width=103.33
band22-frequency=3580.1599999999999
installed=true
band19-frequency=1794.3299999999999
band24-frequency=5674.1599999999999
band12-type='peak'
band19-gain=0.0
band29-type='peak'
band26-gain=0.0
band5-gain=0.0
band26-frequency=8992.9400000000005
band10-frequency=225.88999999999999
band28-frequency=14252.860000000001
band1-width=6.5199999999999996
band8-width=32.670000000000002
band11-type='peak'
band12-frequency=358.01999999999998
band18-gain=0.0
band7-type='peak'
band28-type='peak'
band25-gain=0.0
band1-frequency=28.440000000000001
band9-gain=0.0
band14-frequency=567.41999999999996
band4-gain=0.0
band6-width=20.620000000000001
band3-frequency=45.07
band21-width=651.94000000000005
band28-width=3267.4200000000001
band14-width=130.08000000000001
input-gain=0.0
band21-frequency=2843.8200000000002
band5-frequency=71.430000000000007
band10-type='peak'
band17-gain=0.0
band6-type='peak'
band4-width=13.0
band27-type='peak'

[sourceoutputs/limiter]
lookahead=5.0
input-gain=0.0
oversampling=1
post-messages=true
limit=0.0
asc-level=0.5
release=50.0
asc=false
installed=true
state=false

[sourceoutputs/filter]
frequency=2000.0
resonance=-3.0
inertia=20.0
mode='12dB/oct Lowpass'
input-gain=0.0
post-messages=true
output-gain=0.0
installed=true
state=false

[sourceoutputs/reverb]
predelay=0.0
room-size='Large'
decay-time=1.5
amount=-12.0
input-gain=0.0
diffusion=0.5
bass-cut=300.0
post-messages=true
treble-cut=5000.0
hf-damp=5000.0
output-gain=0.0
installed=true
dry=0.0
state=false

[sourceoutputs]
plugins=['gate', 'multiband_gate', 'webrtc', 'limiter', 'compressor', 'multiband_compressor', 'filter', 'equalizer', 'deesser', 'reverb', 'pitch']

[sourceoutputs/multibandcompressor]
mode='LR8'
makeup1=0.0
makeup2=0.0
attack0=150.0
attack1=150.0
bypass2=false
attack3=150.0
solo2=false
attack2=150.0
solo1=false
makeup0=0.0
knee0=9.0
threshold0=-12.0
threshold1=-12.0
solo3=false
threshold3=-12.0
freq0=120.0
freq1=1000.0
freq2=6000.0
threshold2=-12.0
solo0=false
state=false
release0=300.0
ratio0=2.0
ratio1=2.0
output-gain=0.0
ratio2=2.0
ratio3=2.0
post-messages=true
release1=300.0
release2=300.0
release3=300.0
input-gain=0.0
bypass0=false
bypass1=false
detection0='RMS'
detection1='RMS'
knee1=9.0
detection2='RMS'
detection3='RMS'
knee3=9.0
knee2=9.0
bypass3=false
installed=true
makeup3=0.0

[sourceoutputs/gate]
range=-24.0
detection='RMS'
stereo-link='Average'
threshold=-18.0
ratio=2.0
post-messages=true
makeup=0.0
attack=20.0
knee=9.0
installed=true
state=false
release=250.0

[sourceoutputs/compressor]
detection='RMS'
stereo-link='Average'
mix=0.0
threshold=-18.0
ratio=2.0
post-messages=true
makeup=0.0
attack=20.0
knee=9.0
installed=true
state=false
release=250.0

[sourceoutputs/multibandgate]
mode='LR8'
makeup1=0.0
makeup2=0.0
attack0=150.0
attack1=150.0
bypass2=false
attack3=150.0
solo2=false
attack2=150.0
solo1=false
makeup0=0.0
knee0=9.0
threshold0=-12.0
threshold1=-12.0
solo3=false
threshold3=-12.0
freq0=120.0
freq1=1000.0
freq2=6000.0
threshold2=-12.0
solo0=false
state=false
release0=300.0
ratio0=2.0
ratio1=2.0
output-gain=0.0
ratio2=2.0
ratio3=2.0
post-messages=true
range0=-24.0
range1=-24.0
range2=-24.0
range3=-24.0
release1=300.0
release2=300.0
release3=300.0
input-gain=0.0
bypass0=false
bypass1=false
detection0='RMS'
detection1='RMS'
knee1=9.0
detection2='RMS'
detection3='RMS'
knee3=9.0
knee2=9.0
bypass3=false
installed=true
makeup3=0.0

[sourceoutputs/deesser]
f1-freq=6000.0
f2-freq=4500.0
detection='RMS'
mode='Wide'
threshold=-18.0
ratio=3.0
f2-q=1.0
f2-level=12.0
post-messages=true
f1-level=0.0
makeup=0.0
sc-listen=false
laxity=15
installed=true
state=false

[sourceoutputs/webrtc]
voice-detection-frame-size-ms=10
voice-detection=false
gain-control=true
echo-suppression-level='moderate'
echo-cancel=true
limiter=true
voice-detection-likelihood='low'
post-messages=true
noise-suppression=true
delay-agnostic=false
high-pass-filter=true
noise-suppression-level='moderate'
installed=true
state=false
target-level-dbfs=3
gain-control-mode='adaptive-digital'
extended-filter=true
compression-gain-db=9
@relh relh closed this as completed Sep 27, 2018
@relh
Copy link
Author

relh commented Sep 27, 2018

I tried to recreate this bug, and the best I could do without rebooting was that whatever volume information PulseEffects shows, it is set to 100 and volume goes up when I click "ON". I'm guessing maybe there is a config that is making my volume auto set to 100 when it turns on?

@relh relh reopened this Sep 27, 2018
@wwmm
Copy link
Owner

wwmm commented Sep 27, 2018

I never saw this happening. Considering you are on ubuntu 18 and have the module module-switch-on-connect loaded you may have problems if your Pulseaudio version is not 12 or above. Try to see if any of the hints in our FAQ makes any change.

When you switch on an app in PulseEffects all that is done is moving this app from the default output device(usually your sound card) to the PulseEffects null sink PulseEffects(apps). The volume should not be touched.

@wwmm
Copy link
Owner

wwmm commented Sep 27, 2018

Run PE from the command line in debug mode G_MESSAGES_DEBUG=pulseeffects pulseeffects what it prints to the terminal may give hints to what is happening.

@relh
Copy link
Author

relh commented Sep 27, 2018

Yeah I only see that the application was destroyed when I try running it that way. Maybe I need to disable run on boot or something:

(pulseeffects:3228): pulseeffects-DEBUG: 09:34:50.303: application: destroyed

I will explore the hints in the FAQ and see if I can get any debugging logs for when this happens after reboot. Thanks for the quick response!

@wwmm
Copy link
Owner

wwmm commented Sep 27, 2018

You probably have an instance of PulseEffects running. First kill it using pulseeffects -q and then restart it in debug mode. You can see other command line options here Command-Line-Options

@relh
Copy link
Author

relh commented Sep 27, 2018

I've toggled it a few times sans reboot, and the volume slider switches to 100 from wherever it is whenever I click the "ON" button. It doesn't seem to show up in logs:

PE log
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.190: application: PE version: 4.3.7
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.191: pulse_manager: context is connecting
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.193: pulse_manager: context is authorizing
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.193: pulse_manager: context is setting name
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.194: pulse_manager: context is ready
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.194: pulse_manager: connected to: unix:/run/user/1000/pulse/native
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.194: pulse_manager: protocol version: 32
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.194: pulse_manager: Pulseaudio version: 12.2
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.194: pulse_manager: default pulseaudio source: alsa_input.pci-0000_00_1f.3.analog-stereo
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.194: pulse_manager: default pulseaudio sink: alsa_output.pci-0000_00_1f.3.analog-stereo
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.194: pulse_manager: loading Pulseeffects applications output sink...
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.194: pulse_manager: default pulseaudio sink sampling rate: 48000 Hz
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.194: pulse_manager: default pulseaudio sink audio format: s32le
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.220: pulse_manager: sink loaded
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.220: pulse_manager: loading Pulseeffects microphone output sink...
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.221: pulse_manager: default pulseaudio source sampling rate: 48000 Hz
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.221: pulse_manager: default pulseaudio source audio format: s32le
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.221: pulse_manager: sink loaded
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.341: presets_manager: user preset directory already exists: /home/relh/.config/PulseEffects
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.623: convolver_ui: irs directory already exists: /home/relh/.config/PulseEffects/irs
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.952: sie: spectrum enabled
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:03.952: soe: spectrum enabled
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:04.607: application_ui: output device changed: alsa_output.pci-0000_00_1f.3.analog-stereo
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:04.607: application_ui: added sink: alsa_output.pci-0000_00_1f.3.analog-stereo
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:04.607: application_ui: input device changed: alsa_input.pci-0000_00_1f.3.analog-stereo
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:04.607: application_ui: added source: alsa_input.pci-0000_00_1f.3.analog-stereo
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:04.608: application_ui: added source: alsa_output.pci-0000_00_1f.3.analog-stereo.monitor
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:12.021: sie: NULL -> READY -> PLAYING
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:12.022: sie: READY -> PAUSED -> PLAYING
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:12.023: sie: pulsesrc latency [us]: 10000
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:12.023: sie: pulsesrc buffer [us]: 200000
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:12.032: sie: bass_enhancer enabled
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:12.036: sie: crystalizer enabled
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:12.039: sie: equalizer enabled
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:12.039: sie: sampling rate: 48000 Hz
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:12.087: sie: pulsesink latency [us]: 10000
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:12.087: sie: pulsesink buffer [us]: 200000
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:14.025: sie: PAUSED -> PLAYING -> VOID_PENDING
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:14.026: sie: total latency: 120 ms
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:15.569: pulse_manager: sink input: Google Play Music Desktop Player, idx = 990 removed from PE
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:15.590: sie: PLAYING -> PAUSED -> VOID_PENDING
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:18.664: pulse_manager: sink input: Google Play Music Desktop Player, idx = 990 moved to PE
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:18.686: sie: PAUSED -> PLAYING -> VOID_PENDING
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:18.687: sie: total latency: 120 ms
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:22.379: pulse_manager: sink input: Google Play Music Desktop Player, idx = 990 removed from PE
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:22.396: sie: PLAYING -> PAUSED -> VOID_PENDING
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:26.180: pulse_manager: sink input: Google Play Music Desktop Player, idx = 990 moved to PE
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:26.201: sie: PAUSED -> PLAYING -> VOID_PENDING
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:26.201: sie: total latency: 120 ms
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:29.846: pulse_manager: sink input: Google Play Music Desktop Player, idx = 990 removed from PE
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:29.868: sie: PLAYING -> PAUSED -> VOID_PENDING
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:30.795: pulse_manager: sink input: Google Play Music Desktop Player, idx = 990 moved to PE
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:30.820: sie: PAUSED -> PLAYING -> VOID_PENDING
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:30.820: sie: total latency: 120 ms
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:46.271: sie: PLAYING -> PAUSED -> VOID_PENDING
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:54.093: sie: PAUSED -> PLAYING -> VOID_PENDING
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:54.093: sie: total latency: 120 ms
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:56.759: pulse_manager: sink input: Google Play Music Desktop Player, idx = 1027 removed from PE
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:56.775: sie: PLAYING -> PAUSED -> VOID_PENDING
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:59.210: pulse_manager: sink input: Google Play Music Desktop Player, idx = 1027 moved to PE
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:59.250: sie: PAUSED -> PLAYING -> VOID_PENDING
(pulseeffects:31479): pulseeffects-DEBUG: 10:48:59.251: sie: total latency: 120 ms
(pulseeffects:31479): pulseeffects-DEBUG: 10:49:01.060: pulse_manager: sink input: Google Play Music Desktop Player, idx = 1027 removed from PE
(pulseeffects:31479): pulseeffects-DEBUG: 10:49:01.090: sie: PLAYING -> PAUSED -> VOID_PENDING
(pulseeffects:31479): pulseeffects-DEBUG: 10:49:03.096: pulse_manager: sink input: Google Play Music Desktop Player, idx = 1027 moved to PE
(pulseeffects:31479): pulseeffects-DEBUG: 10:49:03.127: sie: PAUSED -> PLAYING -> VOID_PENDING
(pulseeffects:31479): pulseeffects-DEBUG: 10:49:03.132: sie: total latency: 120 ms
(pulseeffects:31479): pulseeffects-DEBUG: 10:49:06.027: pulse_manager: sink input: Google Play Music Desktop Player, idx = 1027 removed from PE
(pulseeffects:31479): pulseeffects-DEBUG: 10:49:06.043: sie: PLAYING -> PAUSED -> VOID_PENDING

@wwmm
Copy link
Owner

wwmm commented Sep 27, 2018

Nothing strange. I think we will have to take a look at Pulseaudio's log. There is a step by step in the advanced section of Reporting-Bugs.

@lindquest
Copy link

Hello!

I'm having a issue very similar to @relh, albeit using the "Enable All Applications" option instead of turning on for each app.

Steps done during the log (attached below):

  • At 21:41:21, No applications were playing audio, and I opened PulseEffects (the main volume hasn't been changed yet);
  • At 21:41:26, I pressed play on Spotify (which "spawned" the application on PE) and the main volume changed to 100%;
  • At 21:41:27, I pressed pause on Spotify (volume is still at 100%);

Environment:

  • Manjaro Linux (Linux 4.18.11)
  • KDE Plasma 5.13.5
  • PulseAudio 12.2
  • PulseEffects 4.3.7
PA log
out 02 21:40:54 P34W pulseaudio[1267]: I: [pulseaudio] client.c: Created 20 "UNIX socket client"
out 02 21:40:54 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Client added for object /org/pulseaudio/core1/client20
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] client.c: Created 21 "Native client (UNIX socket client)"
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Client added for object /org/pulseaudio/core1/client21
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Protocol version: remote 32, local 32
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: SHM possible: yes
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Negotiated SHM: yes
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Memfd possible: yes
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Negotiated SHM type: shared memfd
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] memblock.c: Using shared memfd memory pool with 1024 slots of size 64,0 KiB each, total size is 64,0 MiB, maximum usable slot size is 65472
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] srbchannel.c: SHM block is 65472 bytes, ringbuffer capacity is 2 * 32712 bytes
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Enabling srbchannel...
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] module-augment-properties.c: Looking for .desktop file for pulseeffects
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Client enabled srbchannel.
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] module-device-restore.c: Restoring volume for sink PulseEffects_apps: front-left: 65536 / 100%,   front-right: 65536 / 100%
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] module-device-restore.c: Restoring mute state for sink PulseEffects_apps: unmuted
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] sink.c: Created sink 3 "PulseEffects_apps" with sample spec float32le 2ch 44100Hz and channel map front-left,front-right
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] sink.c:     device.description = "PulseEffects(apps)"
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] sink.c:     device.class = "sound"
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] sink.c:     device.icon_name = "audio-card"
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] module-device-manager.c: Database contains no data for key: source:PulseEffects_apps.monitor
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] source.c: Created source 4 "PulseEffects_apps.monitor" with sample spec float32le 2ch 44100Hz and channel map front-left,front-right
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] source.c:     device.description = "Monitor of PulseEffects(apps)"
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] source.c:     device.class = "monitor"
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] source.c:     device.icon_name = "audio-input-microphone"
out 02 21:41:21 P34W pulseaudio[1267]: D: [null-sink] module-null-sink.c: Thread starting up
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] sink.c: PulseEffects_apps: state: INIT -> IDLE
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] source.c: PulseEffects_apps.monitor: state: INIT -> IDLE
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device added for object /org/pulseaudio/core1/source4
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Source added for object /org/pulseaudio/core1/source4
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] module-device-restore.c: Could not set format on sink PulseEffects_apps
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device added for object /org/pulseaudio/core1/sink3
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Sink added for object /org/pulseaudio/core1/sink3
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] module-suspend-on-idle.c: Sink PulseEffects_apps becomes idle, timeout in 5 seconds.
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] sink-input.c: Starting to move sink input 3 from 'alsa_output.pci-0000_00_1b.0.analog-stereo'
out 02 21:41:21 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Requesting rewind due to started move
out 02 21:41:21 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Requested volume: front-left: 45871 /  70% / -9,30 dB,   front-right: 45871 /  70% / -9,30 dB
out 02 21:41:21 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Got hardware volume: front-left: 46396 /  71% / -9,00 dB,   front-right: 46396 /  71% / -9,00 dB
out 02 21:41:21 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Calculated software volume: front-left: 64794 /  99% / -0,30 dB,   front-right: 64794 /  99% / -0,30 dB (accurate-enough=no)
out 02 21:41:21 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Volume going up to 46396 at 911100321
out 02 21:41:21 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] ratelimit.c: 651 events suppressed
out 02 21:41:21 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Next volume change in 7994 usec
out 02 21:41:21 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Next volume change in 7983 usec
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c: Trying to change sample rate
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] protocol-native.c: Requested tlength=2000,00 ms, minreq=20,00 ms
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Requested latency=980,00 ms, Received latency=980,00 ms
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] resampler.c: Resampler:
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] resampler.c:   rate 44100 -> 44100 (method copy)
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] resampler.c:   format s16le -> float32le (intermediate s16le)
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] resampler.c:   channels 2 -> 2 (resampling 2)
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=8, prebuf=0, minreq=1 maxrewind=0
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=8, prebuf=0, minreq=8 maxrewind=0
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] sink-input.c: Updated resampler for sink input 3
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] sink-input.c: The volume of sink input 3 changed from front-left: 27522 /  42% / -22,61 dB,   front-right: 27522 /  42% / -22,61 dB to front-left: 39321 /  60% / -13,31 dB,   front-right: 39321 /  60% / -13,31 dB.
out 02 21:41:21 P34W pulseaudio[1267]: D: [null-sink] module-null-sink.c: Requested to rewind 345744 bytes.
out 02 21:41:21 P34W pulseaudio[1267]: D: [null-sink] sink.c: Processing rewind...
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] sink-input.c: Successfully moved sink input 3 to PulseEffects_apps.
out 02 21:41:21 P34W pulseaudio[1267]: D: [null-sink] sink-input.c: Have to rewind 345744 bytes on render memblockq.
out 02 21:41:21 P34W pulseaudio[1267]: D: [null-sink] source.c: Processing rewind...
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
out 02 21:41:21 P34W pulseaudio[1267]: D: [null-sink] module-null-sink.c: Rewound 345744 bytes.
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] module.c: Loaded "module-null-sink" (index: #32; argument: "sink_name=PulseEffects_apps sink_properties=device.description="PulseEffects(apps)"device.class="sound" channels=2 rate=44100").
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Module added for object /org/pulseaudio/core1/module32
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] module-device-restore.c: Restoring mute state for sink PulseEffects_mic: unmuted
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] sink.c: Created sink 4 "PulseEffects_mic" with sample spec float32le 2ch 44100Hz and channel map front-left,front-right
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] sink.c:     device.description = "PulseEffects(mic)"
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] sink.c:     device.class = "sound"
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] sink.c:     device.icon_name = "audio-card"
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] module-device-manager.c: Database contains no data for key: source:PulseEffects_mic.monitor
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] source.c: Created source 5 "PulseEffects_mic.monitor" with sample spec float32le 2ch 44100Hz and channel map front-left,front-right
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] source.c:     device.description = "Monitor of PulseEffects(mic)"
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] source.c:     device.class = "monitor"
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] source.c:     device.icon_name = "audio-input-microphone"
out 02 21:41:21 P34W pulseaudio[1267]: D: [null-sink] module-null-sink.c: Thread starting up
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] sink.c: PulseEffects_mic: state: INIT -> IDLE
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] source.c: PulseEffects_mic.monitor: state: INIT -> IDLE
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device added for object /org/pulseaudio/core1/source5
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Source added for object /org/pulseaudio/core1/source5
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] module-device-restore.c: Could not set format on sink PulseEffects_mic
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device added for object /org/pulseaudio/core1/sink4
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Sink added for object /org/pulseaudio/core1/sink4
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] module-suspend-on-idle.c: Sink PulseEffects_mic becomes idle, timeout in 5 seconds.
out 02 21:41:21 P34W pulseaudio[1267]: I: [pulseaudio] module.c: Loaded "module-null-sink" (index: #33; argument: "sink_name=PulseEffects_mic sink_properties=device.description="PulseEffects(mic)"device.class="sound" channels=2 rate=44100").
out 02 21:41:21 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Module added for object /org/pulseaudio/core1/module33
out 02 21:41:21 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Volume change to 46396 at 911100321 was written 21 usec late
out 02 21:41:22 P34W pulseaudio[1267]: I: [pulseaudio] client.c: Created 22 "Native client (UNIX socket client)"
out 02 21:41:22 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Client added for object /org/pulseaudio/core1/client22
out 02 21:41:22 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Protocol version: remote 32, local 32
out 02 21:41:22 P34W pulseaudio[1267]: I: [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
out 02 21:41:22 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: SHM possible: yes
out 02 21:41:22 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Negotiated SHM: yes
out 02 21:41:22 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Memfd possible: yes
out 02 21:41:22 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Negotiated SHM type: shared memfd
out 02 21:41:22 P34W pulseaudio[1267]: D: [pulseaudio] memblock.c: Using shared memfd memory pool with 1024 slots of size 64,0 KiB each, total size is 64,0 MiB, maximum usable slot size is 65472
out 02 21:41:22 P34W pulseaudio[1267]: D: [pulseaudio] srbchannel.c: SHM block is 65472 bytes, ringbuffer capacity is 2 * 32712 bytes
out 02 21:41:22 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Enabling srbchannel...
out 02 21:41:22 P34W pulseaudio[1267]: D: [pulseaudio] module-augment-properties.c: Looking for .desktop file for pulseeffects
out 02 21:41:22 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Client enabled srbchannel.
out 02 21:41:26 P34W pulseaudio[1267]: D: [null-sink] protocol-native.c: Requesting rewind due to end of underrun.
out 02 21:41:26 P34W pulseaudio[1267]: D: [null-sink] sink-input.c: Requesting rewind due to uncorking
out 02 21:41:26 P34W pulseaudio[1267]: D: [null-sink] module-null-sink.c: Requested to rewind 345744 bytes.
out 02 21:41:26 P34W pulseaudio[1267]: D: [null-sink] sink.c: Processing rewind...
out 02 21:41:26 P34W pulseaudio[1267]: D: [null-sink] source.c: Processing rewind...
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-suspend-on-idle.c: Sink PulseEffects_apps becomes busy, resuming.
out 02 21:41:26 P34W pulseaudio[1267]: D: [null-sink] module-null-sink.c: Rewound 200424 bytes.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] sink.c: PulseEffects_apps: state: IDLE -> RUNNING
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] client.c: Created 23 "Native client (UNIX socket client)"
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Client added for object /org/pulseaudio/core1/client23
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Protocol version: remote 32, local 32
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: SHM possible: yes
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Negotiated SHM: yes
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Memfd possible: yes
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Negotiated SHM type: shared memfd
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] memblock.c: Using shared memfd memory pool with 1024 slots of size 64,0 KiB each, total size is 64,0 MiB, maximum usable slot size is 65472
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] srbchannel.c: SHM block is 65472 bytes, ringbuffer capacity is 2 * 32712 bytes
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Enabling srbchannel...
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-augment-properties.c: Looking for .desktop file for pulseeffects
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Client enabled srbchannel.
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] client.c: Created 24 "Native client (UNIX socket client)"
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Client added for object /org/pulseaudio/core1/client24
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Protocol version: remote 32, local 32
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: SHM possible: yes
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Negotiated SHM: yes
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Memfd possible: yes
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Negotiated SHM type: shared memfd
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] memblock.c: Using shared memfd memory pool with 1024 slots of size 64,0 KiB each, total size is 64,0 MiB, maximum usable slot size is 65472
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] srbchannel.c: SHM block is 65472 bytes, ringbuffer capacity is 2 * 32712 bytes
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Enabling srbchannel...
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-augment-properties.c: Looking for .desktop file for pulseeffects
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Client enabled srbchannel.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-stream-restore.c: Not restoring device for stream source-output-by-application-id:com.github.wwmm.pulseeffects.sinkinputs, because already set
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-device-manager.c: Not restoring device for stream because already set.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-intended-roles.c: Not setting device for stream Record Stream, because already set.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] source-output.c: Negotiated format: pcm, format.sample_format = "\"float32le\""  format.rate = "44100"  format.channels = "2"  format.channel_map = "\"front-left,front-right\""
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] module-stream-restore.c: Restoring volume for source output source-output-by-application-id:com.github.wwmm.pulseeffects.sinkinputs.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-suspend-on-idle.c: Sink PulseEffects_apps becomes busy, resuming.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=8, prebuf=0, minreq=1 maxrewind=0
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=8, prebuf=0, minreq=8 maxrewind=0
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] source-output.c: Created output 1 "Record Stream" on PulseEffects_apps.monitor with sample spec float32le 2ch 44100Hz and channel map front-left,front-right
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] source-output.c:     application.id = "com.github.wwmm.pulseeffects.sinkinputs"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] source-output.c:     media.name = "Record Stream"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] source-output.c:     application.name = "PulseEffects"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] source-output.c:     native-protocol.peer = "UNIX socket client"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] source-output.c:     native-protocol.version = "32"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] source-output.c:     application.icon_name = "pulseeffects"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] source-output.c:     application.process.id = "4388"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] source-output.c:     application.process.user = "alexandre"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] source-output.c:     application.process.host = "P34W"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] source-output.c:     application.process.binary = "pulseeffects"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] source-output.c:     application.language = "en_US.UTF-8"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] source-output.c:     window.x11.display = ":0"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] source-output.c:     application.process.machine_id = "a3ddb51254d846109f91f7f99a36e8dd"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] source-output.c:     application.process.session_id = "2"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] source-output.c:     module-stream-restore.id = "source-output-by-application-id:com.github.wwmm.pulseeffects.sinkinputs"
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] memblockq.c: memblockq requested: maxlength=10584, tlength=0, base=8, prebuf=1, minreq=0 maxrewind=0
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=10584, tlength=10584, base=8, prebuf=8, minreq=8 maxrewind=0
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] protocol-native.c: Final latency 20,00 ms = 10,00 ms + 10,00 ms
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/record_stream1
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-stream-restore.c: Not restoring device for stream sink-input-by-application-id:com.github.wwmm.pulseeffects.sinkinputs, because already set to 'alsa_output.pci-0000_00_1b.0.analog-stereo'.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-device-manager.c: Not restoring device for stream because already set.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-intended-roles.c: Not setting device for stream pulsesink probe, because already set.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"s16le\""  format.rate = "44100"  format.channels = "2"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c: Trying to change sample rate
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] reserve-wrap.c: Successfully acquired reservation lock on device 'Audio1'
out 02 21:41:26 P34W pulseaudio[1267]: I: [alsa-sink-ALC282 Analog] alsa-sink.c: Trying resume...
out 02 21:41:26 P34W pulseaudio[1267]: I: [alsa-sink-ALC282 Analog] alsa-util.c: Trying to disable ALSA period wakeups, using timers only
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-util.c: Maximum hw buffer size is 11888 ms
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-util.c: Set buffer size first (to 88200 samples), period size second (to 44100 samples).
out 02 21:41:26 P34W pulseaudio[1267]: I: [alsa-sink-ALC282 Analog] alsa-util.c: ALSA period wakeups disabled
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: hwbuf_unused=0
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: setting avail_min=87319
out 02 21:41:26 P34W pulseaudio[1267]: I: [alsa-sink-ALC282 Analog] alsa-sink.c: Time scheduling watermark is 20,00ms
out 02 21:41:26 P34W pulseaudio[1267]: I: [alsa-sink-ALC282 Analog] alsa-sink.c: Resumed successfully...
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: suspend_cause: IDLE -> (none)
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: SUSPENDED -> IDLE
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
out 02 21:41:26 P34W pulseaudio[1267]: I: [alsa-sink-ALC282 Analog] alsa-sink.c: Starting playback.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: suspend_cause: IDLE -> (none)
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] source.c: alsa_output.pci-0000_00_1b.0.analog-stereo.monitor: state: SUSPENDED -> IDLE
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] resampler.c: Resampler:
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] resampler.c:   rate 44100 -> 44100 (method copy)
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] resampler.c:   format s16le -> s32le (intermediate s16le)
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] resampler.c:   channels 2 -> 2 (resampling 2)
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=8, prebuf=0, minreq=1 maxrewind=0
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=8, prebuf=0, minreq=8 maxrewind=0
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c: Created input 5 "pulsesink probe" on alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.id = "com.github.wwmm.pulseeffects.sinkinputs"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     media.name = "pulsesink probe"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.name = "PulseEffects"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     native-protocol.version = "32"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.icon_name = "pulseeffects"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.process.id = "4388"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.process.user = "alexandre"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.process.host = "P34W"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.process.binary = "pulseeffects"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.language = "en_US.UTF-8"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     window.x11.display = ":0"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.process.machine_id = "a3ddb51254d846109f91f7f99a36e8dd"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.process.session_id = "2"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-id:com.github.wwmm.pulseeffects.sinkinputs"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] protocol-native.c: Requested tlength=272,11 ms, minreq=20,00 ms
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Requested latency=116,05 ms, Received latency=116,05 ms
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=27528, base=4, prebuf=24004, minreq=3528 maxrewind=0
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=27528, base=4, prebuf=24004, minreq=3528 maxrewind=0
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] protocol-native.c: Final latency 272,11 ms = 116,05 ms + 2*20,00 ms + 116,05 ms
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Latency set to 116,05ms
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: hwbuf_unused=664664
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: setting avail_min=87319
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Requesting rewind due to latency change.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Requested volume: front-left: 45871 /  70% / -9,30 dB,   front-right: 45871 /  70% / -9,30 dB
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Got hardware volume: front-left: 46396 /  71% / -9,00 dB,   front-right: 46396 /  71% / -9,00 dB
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Calculated software volume: front-left: 64794 /  99% / -0,30 dB,   front-right: 64794 /  99% / -0,30 dB (accurate-enough=no)
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Volume not changing
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Requested to rewind 705600 bytes.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Limited to 704784 bytes.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: before: 88098
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: after: 88098
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Rewound 704784 bytes.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Processing rewind...
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: latency = 1896
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink-input.c: Have to rewind 704784 bytes on render memblockq.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] source.c: Processing rewind...
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream5
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Client pulseeffects changes volume of source output Record Stream.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-suspend-on-idle.c: Sink PulseEffects_apps becomes busy, resuming.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] source.c: PulseEffects_apps.monitor: state: IDLE -> RUNNING
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream removed from object /org/pulseaudio/core1/playback_stream5
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: hwbuf_unused=0
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: setting avail_min=87319
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Requested volume: front-left: 45871 /  70% / -9,30 dB,   front-right: 45871 /  70% / -9,30 dB
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Got hardware volume: front-left: 46396 /  71% / -9,00 dB,   front-right: 46396 /  71% / -9,00 dB
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Calculated software volume: front-left: 64794 /  99% / -0,30 dB,   front-right: 64794 /  99% / -0,30 dB (accurate-enough=no)
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Volume not changing
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Requested to rewind 705600 bytes.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Limited to 37880 bytes.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: before: 4735
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c: Freeing input 5 "pulsesink probe"
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: after: 4735
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Rewound 37880 bytes.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Processing rewind...
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: latency = 2109
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] source.c: Processing rewind...
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-stream-restore.c: Not restoring device for stream sink-input-by-application-id:com.github.wwmm.pulseeffects.sinkinputs, because already set to 'alsa_output.pci-0000_00_1b.0.analog-stereo'.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-device-manager.c: Not restoring device for stream because already set.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-intended-roles.c: Not setting device for stream Playback Stream, because already set.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"float32le\""  format.channels = "2"  format.rate = "44100"  format.channel_map = "\"front-left,front-right\""
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c: Trying to change sample rate
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] resampler.c: Resampler:
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] resampler.c:   rate 44100 -> 44100 (method copy)
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] resampler.c:   format float32le -> s32le (intermediate float32le)
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] resampler.c:   channels 2 -> 2 (resampling 2)
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=8, prebuf=0, minreq=1 maxrewind=0
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=8, prebuf=0, minreq=8 maxrewind=0
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c: Created input 6 "Playback Stream" on alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec float32le 2ch 44100Hz and channel map front-left,front-right
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.id = "com.github.wwmm.pulseeffects.sinkinputs"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     media.name = "Playback Stream"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.name = "PulseEffects"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     native-protocol.version = "32"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.icon_name = "pulseeffects"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.process.id = "4388"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.process.user = "alexandre"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.process.host = "P34W"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.process.binary = "pulseeffects"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.language = "en_US.UTF-8"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     window.x11.display = ":0"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.process.machine_id = "a3ddb51254d846109f91f7f99a36e8dd"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     application.process.session_id = "2"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-id:com.github.wwmm.pulseeffects.sinkinputs"
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] protocol-native.c: Requested tlength=30,00 ms, minreq=10,00 ms
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-native.c: Requested latency=5,00 ms, Received latency=5,00 ms
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=8824, base=8, prebuf=0, minreq=3528 maxrewind=0
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=8824, base=8, prebuf=0, minreq=3528 maxrewind=0
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] protocol-native.c: Final latency 30,01 ms = 5,01 ms + 2*10,00 ms + 5,00 ms
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] sink.c: The reference volume of sink alsa_output.pci-0000_00_1b.0.analog-stereo changed from front-left: 45871 /  70% / -9,30 dB,   front-right: 45871 /  70% / -9,30 dB to front-left: 65536 / 100% / 0,00 dB,   front-right: 65536 / 100% / 0,00 dB.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Latency set to 5,00ms
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: hwbuf_unused=703840
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: setting avail_min=88091
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Requesting rewind due to latency change.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Requested volume: front-left: 65536 / 100% / 0,00 dB,   front-right: 65536 / 100% / 0,00 dB
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Got hardware volume: front-left: 65536 / 100% / 0,00 dB,   front-right: 65536 / 100% / 0,00 dB
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Calculated software volume: front-left: 65536 / 100% / 0,00 dB,   front-right: 65536 / 100% / 0,00 dB (accurate-enough=yes)
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Volume going up to 65536 at 917940766
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Next volume change in 2008276 usec
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Requested to rewind 705600 bytes.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Limited to 705136 bytes.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: before: 88142
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: after: 88142
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Rewound 705136 bytes.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Processing rewind...
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: latency = 1562
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Next volume change in 9561 usec
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink-input.c: Have to rewind 705136 bytes on render memblockq.
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] source.c: Processing rewind...
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream6
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Next volume change in 9533 usec
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Next volume change in 9425 usec
out 02 21:41:26 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Volume change to 65536 at 915942084 was written 26 usec late
out 02 21:41:26 P34W pulseaudio[1267]: I: [pulseaudio] module-suspend-on-idle.c: Sink PulseEffects_mic idle for too long, suspending ...
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] sink.c: PulseEffects_mic: suspend_cause: (none) -> IDLE
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] sink.c: PulseEffects_mic: state: IDLE -> SUSPENDED
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] source.c: PulseEffects_mic.monitor: suspend_cause: (none) -> IDLE
out 02 21:41:26 P34W pulseaudio[1267]: D: [pulseaudio] source.c: PulseEffects_mic.monitor: state: IDLE -> SUSPENDED
out 02 21:41:27 P34W pulseaudio[1267]: D: [null-sink] sink-input.c: Requesting rewind due to corking
out 02 21:41:27 P34W pulseaudio[1267]: D: [null-sink] module-null-sink.c: Requested to rewind 3528 bytes.
out 02 21:41:27 P34W pulseaudio[1267]: D: [null-sink] sink.c: Processing rewind...
out 02 21:41:27 P34W pulseaudio[1267]: D: [null-sink] sink-input.c: Have to rewind 3528 bytes on render memblockq.
out 02 21:41:27 P34W pulseaudio[1267]: D: [null-sink] sink-input.c: Have to rewind 1764 bytes on implementor.
out 02 21:41:27 P34W pulseaudio[1267]: D: [null-sink] source.c: Processing rewind...
out 02 21:41:27 P34W pulseaudio[1267]: D: [null-sink] module-null-sink.c: Rewound 3528 bytes.
out 02 21:41:27 P34W pulseaudio[1267]: D: [pulseaudio] sink.c: PulseEffects_apps: state: RUNNING -> IDLE
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink-input.c: Requesting rewind due to uncorking
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Requested to rewind 1760 bytes.
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Limited to 1296 bytes.
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: before: 162
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: after: 162
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Rewound 1296 bytes.
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Processing rewind...
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: latency = 1544
out 02 21:41:27 P34W pulseaudio[1267]: D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] source.c: Processing rewind...
out 02 21:41:27 P34W pulseaudio[1267]: D: [pulseaudio] sink.c: alsa_output.pci-0000_00_1b.0.analog-stereo: state: IDLE -> RUNNING
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] protocol-native.c: Requesting rewind due to rewrite.
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Requested to rewind 1296 bytes.
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Limited to 1296 bytes.
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: before: 162
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: after: 162
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] alsa-sink.c: Rewound 1296 bytes.
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: Processing rewind...
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink.c: latency = 1349
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink-input.c: Have to rewind 1296 bytes on render memblockq.
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] sink-input.c: Have to rewind 1296 bytes on implementor.
out 02 21:41:27 P34W pulseaudio[1267]: D: [alsa-sink-ALC282 Analog] source.c: Processing rewind...

@wwmm
Copy link
Owner

wwmm commented Oct 3, 2018

This is bizarre... I can see in the logs that the volume is being changed but Pulseaudio does not tell who asked for this. I know for sure that PE did not do that. These are the lines related to the volume change:

[alsa-sink-ALC282 Analog] sink.c: Requesting rewind due to started move
[alsa-sink-ALC282 Analog] alsa-sink.c: Requested volume: front-left: 45871 /  70% / -9,30 dB,   front-right: 45871 /  70% / -9,30 dB
[alsa-sink-ALC282 Analog] alsa-sink.c: Got hardware volume: front-left: 46396 /  71% / -9,00 dB,   front-right: 46396 /  71% / -9,00 dB
[alsa-sink-ALC282 Analog] alsa-sink.c: Calculated software volume: front-left: 64794 /  99% / -0,30 dB,   front-right: 64794 /  99% / -0,30 dB (accurate-enough=no)
[alsa-sink-ALC282 Analog] sink.c: Volume going up to 46396 at 911100321

The hardware volume stays at 71% but Pulseaudio is changing its software volume to 100% right after PE sink is loaded. In order words before you hit play. I wonder if this is related to a Pulseaudio feature called flat volume. Which value do you have for the variable flat-volumes in the file /etc/pulse/daemon.conf? Also check if you have a file name daemon.conf in the directory ~/.config/pulse. Pulseaudio gives more priority to files in this folder.

@relh
Copy link
Author

relh commented Oct 3, 2018

I have

flat-volumes = no

in my /etc/pulse/daemon.conf and nothing in my home config

@lindquest
Copy link

I have flat-volumes = no for both /etc/pulse/daemon.conf and ~/.config/pulse/daemon.conf.

@lindquest
Copy link

Verifying the output of pulseaudio --dump-conf, I noticed that it was reading the configuration from another file (~/.pulse/daemon.conf), and there the setting flat-volumes was missing, which makes PA default to flat-volumes = yes. I added the option to this other file, and the issue disappeared for me.

@wwmm
Copy link
Owner

wwmm commented Oct 7, 2018

I did not know about this option. Very useful

@flipwise
Copy link
Contributor

Arch Linux and derivatives make the sane decision of disabling flat volumes. Other distros follow upstream, which is pretty dangerous because you can get blasted with 100% volume when turning on PE. Anyway, that's what happened to me when I accidentally turned on flat volumes. Perhaps it would be good to put this in the FAQ, @wwmm?

@wwmm
Copy link
Owner

wwmm commented Oct 23, 2018

Good idea. I added it to the FAQ

@wwmm wwmm closed this as completed Oct 23, 2018
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

4 participants